var/log/Otclient
01/25:13:12:04: /tvbin/TClient: inside TClient
01/25:13:12:04: /tvbin/TClient: No previously downloaded files remain in /var/packages
01/25:13:12:04: /tvbin/TClient: TClient: invoking init w/argv=-h -backup
01/25:13:12:04: /tvbin/TClient: writing In Progress to LastCallStatus
01/25:13:12:04: /tvbin/TClient: TClient init: 0
01/25:13:12:04: /tvbin/TClient: dial config code: () found
01/25:13:12:05: /tvbin/TClient: no line matches code =
01/25:13:12:05: /tvbin/TClient: Using default TiVo Service params
01/25:13:12:05: /tvbin/TClient: updateStatus: In Progress EX| 29 48
01/25:13:12:05: /tvbin/TClient: writing In Progress to LastCallStatus
01/25:13:12:05: /tvbin/TClient: SendDialupEvent 30 9 EX|29
01/25:13:12:05: /tvbin/TClient: Host = 204.176.49.3
01/25:13:12:05: /tvbin/TClient: Port = 80
01/25:13:12:05: /tvbin/TClient: Phone = 01274 DECOMISSIONED
01/25:13:12:05: /tvbin/TClient: updateStatus: In Progress EX| 29 8
01/25:13:12:05: /tvbin/TClient: SendDialupEvent 30 9 EX|29
retrying after errTmBackgroundHoldoff ...
retrying after errTmBackgroundHoldoff ...
01/25:13:12:13: /tvbin/TClient: DataSets may not be enabled, could not find IrDbVersion: DataSet IrDbVersion does not exist.
01/25:13:12:13: /tvbin/TClient: DataSets may not be enabled, could not find GenreVersion: DataSet GenreVersion does not exist.
01/25:13:12:13: /tvbin/TClient: DataSets may not be enabled, could not find LogoVersion: DataSet LogoVersion does not exist.
01/25:13:12:13: /tvbin/TClient: DataSets may not be enabled, could not find AffiliationVersion: DataSet AffiliationVersion does not exist.
01/25:13:12:13: /tvbin/TClient: DataSets may not be enabled, could not find ShowcaseVersion: DataSet ShowcaseVersion does not exist.
01/25:13:12:13: /tvbin/TClient: mrsigtype=3 mrconnector=7...
01/25:13:12:13: /tvbin/TClient: mrsigtype=1 mrconnector=1...
01/25:13:12:14: /tvbin/TClient: Using error search string: D15[@]813
01/25:13:12:23: /tvbin/TClient: encryptLog called with infile = /var/persist/thumbslog.log.gz outfile = /var/persist/thumbslog.log.gz.bfg keyname = BACKHAUL_THUMB
01/25:13:12:23: /tvbin/TClient: EncryptLog Could not find /var/persist/thumbslog.log.gz
01/25:13:12:23: /tvbin/TClient: encryptLog called with infile = /var/log/tivoLog.prv.gz outfile = /var/log/tivoLog.prv.gz.bfg keyname = BACKHAUL_PRIVATE
01/25:13:12:23: /tvbin/TClient: bfkey = 0x3ED6CE6AAB48A083F4B8F58660E34DB4
01/25:13:12:23: /tvbin/TClient: Key value =
01/25:13:12:23: /tvbin/TClient: EncryptLog could not super-encrypt the key because key was empty
01/25:13:12:23: /tvbin/TClient: updateStatus: In Progress EX| 28 7
01/25:13:12:23: /tvbin/TClient: SendDialupEvent 30 9 EX|28
01/25:13:12:23: /tvbin/TClient: creating keyserver query
01/25:13:12:32: /tvbin/TClient: setting up Ident Block
01/25:13:12:32: /tvbin/TClient: returning messages:
01/25:13:12:32: /tvbin/TClient: getting current software
01/25:13:12:32: /tvbin/TClient: found name = 2.5.5-01-1-023
01/25:13:12:34: /tvbin/TClient: found name = ACTIVE
01/25:13:12:34: /tvbin/TClient: Found 2.5.5-01-1 running
01/25:13:12:34: /tvbin/TClient: getting current showcases
01/25:13:12:34: /tvbin/TClient: getting locationID
01/25:13:12:34: /tvbin/TClient: Removing DBS objects in guided setup.
01/25:13:12:34: /tvbin/TClient: Current postalcode: 01000
01/25:13:12:34: /tvbin/TClient: Nothing found so returning: |DBS-0
01/25:13:12:34: /tvbin/TClient: getting headendID
01/25:13:12:34: /tvbin/TClient: skipping getHeadendIDList on SETUPHEAD call
01/25:13:12:34: /tvbin/TClient: setting up TCD411 block
01/25:13:12:34: /tvbin/TClient: setting area code:
01/25:13:12:34: /tvbin/TClient:
01/25:13:12:34: /tvbin/TClient: Start TCD411 Request ==================
01/25:13:12:34: /tvbin/TClient: areaCode:
01/25:13:12:34: /tvbin/TClient: prefix: {}
01/25:13:12:34: /tvbin/TClient: dialInAreaCode: {}
01/25:13:12:34: /tvbin/TClient: curPhNum:
01/25:13:12:34: /tvbin/TClient: objVer:
01/25:13:12:34: /tvbin/TClient: tcdId: 0230000601E8783
01/25:13:12:34: /tvbin/TClient: callId: 1327497125
01/25:13:12:34: /tvbin/TClient: dialPrefix:
01/25:13:12:34: /tvbin/TClient: dialToneCheck:
01/25:13:12:34: /tvbin/TClient: offHookDetect: 1
01/25:13:12:34: /tvbin/TClient: tonePulseDial: P
01/25:13:12:34: /tvbin/TClient: callWaitPrefix:
01/25:13:12:34: /tvbin/TClient: tollFreeAuth:
01/25:13:12:34: /tvbin/TClient: callType: TS_SR_ACCEPTED
01/25:13:12:34: /tvbin/TClient: swVerName: 2.5.5-01-1-023
01/25:13:12:34: /tvbin/TClient: End TCD411 Request ==================
01/25:13:12:34: /tvbin/TClient:
01/25:13:12:34: /tvbin/TClient: Start Ident =====================
01/25:13:12:34: /tvbin/TClient: version: 3
01/25:13:12:34: /tvbin/TClient: centerID: 0230000601E8783
01/25:13:12:34: /tvbin/TClient: reasonCode: 6
01/25:13:12:34: /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/25:13:12:34: /tvbin/TClient: locationID: 01000-0|DBS-0
01/25:13:12:34: /tvbin/TClient: sequenceCookie:
01/25:13:12:34: /tvbin/TClient: headendID:
01/25:13:12:34: /tvbin/TClient: showcaseDesc: showcase0-0
01/25:13:12:34: /tvbin/TClient: inventoryFile:
01/25:13:12:34: /tvbin/TClient: waitingCount: 0
01/25:13:12:34: /tvbin/TClient: dialConfig:
01/25:13:12:34: /tvbin/TClient: confInfo:
01/25:13:12:34: /tvbin/TClient: messageDesc:
01/25:13:12:34: /tvbin/TClient: irdbVersion:
01/25:13:12:34: /tvbin/TClient: genreVersion:
01/25:13:12:34: /tvbin/TClient: logoVersion:
01/25:13:12:34: /tvbin/TClient: affiliationVersion:
01/25:13:12:34: /tvbin/TClient: showcaseVersion:
01/25:13:12:34: /tvbin/TClient: swVerName: 2.5.5-01-1-023
01/25:13:12:34: /tvbin/TClient: configParameterList: zip=01000,dar=,rcq=,tz=,as=,tun=,suc=
01/25:13:12:34: /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/25:13:12:34: /tvbin/TClient: apgOnly:
01/25:13:12:34: /tvbin/TClient: useChksums: TRUE
01/25:13:12:34: /tvbin/TClient: premiumShowcases:
01/25:13:12:34: /tvbin/TClient: captureRequests:
01/25:13:12:34: /tvbin/TClient: menuItems:
01/25:13:12:34: /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/25:13:12:34: /tvbin/TClient: rbBackgrounds:
01/25:13:12:34: /tvbin/TClient: dataGroupList: CR_Standard|DG_standard|MI_Standard|SF_SerialLogging|SW_released
01/25:13:12:34: /tvbin/TClient: lstAvalancheDnload: 0,0 0,0
01/25:13:12:34: /tvbin/TClient: userInitiated: 1
01/25:13:12:34: /tvbin/TClient: End Ident =====================
01/25:13:12:34: /tvbin/TClient:
01/25:13:12:34: /tvbin/TClient: Logging camid.
01/25:13:12:34: /tvbin/TClient: CAM_ID not found.
01/25:13:12:34: /tvbin/TClient: Ird Id.
01/25:13:12:34: /tvbin/TClient: updateStatus: In Progress CL| 30 24
01/25:13:12:34: /tvbin/TClient: SendDialupEvent 30 9 CL|30
01/25:13:12:34: /tvbin/TClient: updateStatus: In Progress ST| 33 14
01/25:13:12:34: /tvbin/TClient: SendDialupEvent 30 9 ST|33
01/25:13:12:34: /tvbin/TClient: About to connect to 204.176.49.3:80
01/25:13:12:34: /tvbin/TClient: Executing HTTP command: /tvbin/http_post /var/log/svclog
http://204.176.49.3:80/tivo-service/mlog.cgi OFF OFF ON
01/25:13:12:35: /tvbin/TClient: about to do TCD411 Call
01/25:13:12:35: /tvbin/TClient: Executing HTTP command: /tvbin/tclient_post 204.176.49.3:80 /var/tmp/TCD411.send /var/tmp/TCD411.recv 300 ON
01/25:13:12:36: /tvbin/TClient: doHttpCall returned: 1
01/25:13:12:36: /tvbin/TClient: reading HTTP header...
01/25:13:12:36: /tvbin/TClient: HTTP header: HTTP/1.1 200 OK
01/25:13:12:36: /tvbin/TClient: HTTP header: Date: Wed, 25 Jan 2012 13:12:34 GMT
01/25:13:12:36: /tvbin/TClient: HTTP header: Server: Apache
01/25:13:12:36: /tvbin/TClient: HTTP header: Content-Length: 58
01/25:13:12:36: /tvbin/TClient: HTTP header: Connection: close
01/25:13:12:36: /tvbin/TClient: HTTP header: Content-Type: text/plain
01/25:13:12:36: /tvbin/TClient: Got end of Header
01/25:13:12:36: /tvbin/TClient: TCD411RespCli::readBlock returned: 1
01/25:13:12:36: /tvbin/TClient:
01/25:13:12:36: /tvbin/TClient: Start TCD411Resp =====================
01/25:13:12:36: /tvbin/TClient: errMsg:
01/25:13:12:36: /tvbin/TClient: areaCodeObj: OK
01/25:13:12:36: /tvbin/TClient: tollFreeAuth: 3
01/25:13:12:36: /tvbin/TClient: tollFreeNum:
01/25:13:12:36: /tvbin/TClient: End TCD411Resp =====================
01/25:13:12:36: /tvbin/TClient: about to do HServer Call
01/25:13:12:36: /tvbin/TClient: Executing HTTP command: /tvbin/tclient_post 204.176.49.3:80 /var/tmp/HServer.send /var/tmp/HServer.recv 300 ON
01/25:13:12:37: /tvbin/TClient: doHttpCall returned: 1
01/25:13:12:37: /tvbin/TClient: reading HTTP header...
01/25:13:12:37: /tvbin/TClient: HTTP header: HTTP/1.1 200 OK
01/25:13:12:37: /tvbin/TClient: HTTP header: Date: Wed, 25 Jan 2012 13:12:35 GMT
01/25:13:12:37: /tvbin/TClient: HTTP header: Server: Apache
01/25:13:12:37: /tvbin/TClient: HTTP header: Connection: close
01/25:13:12:37: /tvbin/TClient: HTTP header: Content-Type: text/plain
01/25:13:12:37: /tvbin/TClient: Got end of Header
01/25:13:12:37: /tvbin/TClient: read 1017 but Content-Length = 0
01/25:13:12:37: /tvbin/TClient: SvrResp::readBlock returned: 1
01/25:13:12:37: /tvbin/TClient:
01/25:13:12:37: /tvbin/TClient: Start SvrResp =====================
01/25:13:12:37: /tvbin/TClient: errMessage:
01/25:13:12:37: /tvbin/TClient: version: 3
01/25:13:12:37: /tvbin/TClient: code: 8
01/25:13:12:37: /tvbin/TClient: softwareList: &http://204.176.49.33:8080/TMS_UK/dynamic/PC/DB/PC-DBS-p15174-v1459.slice.bnd chksum#0xFCA3552329954FAE9C56A188DB6593F95F0FD71C|&http://204.176.49.33:8080/static/RM/RM-ClearHeadendVersion.runme chksum#0x21A319EAF690F1C52D725F69C5D187083D327776|&http://204.176.49.33:8080/static/RM/RM-ClearHeadendVersion.runme.sig chksum#0x9C49852CA40C02611ED56C6B1A624E3766A32DEF|&http://204.176.49.33:8080/TMS_UK/content/15174-1-5/Irdb/IR-standard-v317.slice.gz chksum#0x4895BCA42C9CBD847DA0DE1845F7DB1ABDDC678E|
01/25:13:12:37: /tvbin/TClient: backChannelPrv: NONE
01/25:13:12:37: /tvbin/TClient: backChannelPub: NONE
01/25:13:12:37: /tvbin/TClient: backChannelLog: NONE
01/25:13:12:37: /tvbin/TClient: backChannelThumb: NONE
01/25:13:12:37: /tvbin/TClient: timeService: /bin/ntpdate -b 204.176.49.10 204.176.49.11 204.176.49.12
01/25:13:12:37: /tvbin/TClient: sequenceCookie:
01/25:13:12:37: /tvbin/TClient: inventoryFile: ids:uk
01/25:13:12:37: /tvbin/TClient: noPrivBackhaul: -1
01/25:13:12:37: /tvbin/TClient: serviceState: 8
01/25:13:12:37: /tvbin/TClient: stateExpiration: 0
01/25:13:12:37: /tvbin/TClient: swSystemName: none
01/25:13:12:37: /tvbin/TClient: infoCode:
01/25:13:12:37: /tvbin/TClient: tcdMessage:
01/25:13:12:37: /tvbin/TClient: globalMessages:
01/25:13:12:37: /tvbin/TClient: keyServer:
01/25:13:12:37: /tvbin/TClient: forceBackhaul: 0
01/25:13:12:37: /tvbin/TClient: publicLogFilter: D15[@]813
01/25:13:12:37: /tvbin/TClient: dbLoadOrder: PG.*
01/25:13:12:37: /tvbin/TClient: regenToken: 0
01/25:13:12:37: /tvbin/TClient: backhaulDataOn: 0
01/25:13:12:37: /tvbin/TClient: personalDataOn: 0
01/25:13:12:37: /tvbin/TClient: dataGroupList: CR_Standard,DG_standard,MI_Standard,SF_SerialLogging,SW_released
01/25:13:12:37: /tvbin/TClient: End SvrResp =======================
01/25:13:12:37: /tvbin/TClient: Connect/POST(s) succeeded
01/25:13:12:37: /tvbin/TClient: current AreaCode object is OK
01/25:13:12:37: /tvbin/TClient: TFA is 3
01/25:13:12:37: /tvbin/TClient: Not overwriting with 3
01/25:13:12:37: /tvbin/TClient: process411 returned: 0
01/25:13:12:38: /tvbin/TClient: updateStatus: In Progress ST| 34 26
01/25:13:12:38: /tvbin/TClient: SendDialupEvent 30 9 ST|34
01/25:13:12:38: /tvbin/TClient: Setting ServiceInfo DataGroupList attribute to CR_Standard,DG_standard,MI_Standard,SF_SerialLogging,SW_released
01/25:13:12:38: /tvbin/TClient: Setting ServiceInfo PublicLogFilter attribute to D15[@]813
01/25:13:12:38: /tvbin/TClient: updateStatus: In Progress ST| 34 26
01/25:13:12:38: /tvbin/TClient: SendDialupEvent 30 9 ST|34
01/25:13:12:38: /tvbin/TClient: starting backhaul: 0
01/25:13:12:38: /tvbin/TClient: Setting ServiceInfo ForceBackhaul attribute to 0
01/25:13:12:38: /tvbin/TClient: starting backhaul2
01/25:13:12:38: /tvbin/TClient: starting backhaul3
01/25:13:12:38: /tvbin/TClient: updateStatus: In Progress ST| 35 21
01/25:13:12:38: /tvbin/TClient: SendDialupEvent 30 9 ST|35
01/25:13:12:38: /tvbin/TClient: processing timeService: /bin/ntpdate -b 204.176.49.10 204.176.49.11 204.176.49.12
01/25:13:12:38: /tvbin/TClient: parsed ntpdate args of -b 204.176.49.10 204.176.49.11 204.176.49.12
01/25:13:12:38: /tvbin/TClient: about to run: /bin/ntpdate -b 204.176.49.10 204.176.49.11 204.176.49.12
01/25:13:12:39: /tvbin/TClient: updateStatus: In Progress DL| 36 23
01/25:13:12:39: /tvbin/TClient: SendDialupEvent 30 9 DL|36
01/25:13:12:39: /tvbin/TClient: updateStatus: In Progress DL| 38 16
01/25:13:12:39: /tvbin/TClient: SendDialupEvent 30 9 DL|38
01/25:13:12:39: /tvbin/TClient: sw system name none is already current
01/25:13:12:39: /tvbin/TClient: Executing HTTP GET: /tvbin/http_get -U
http://204.176.49.33:8080/TMS_UK/dynami ... .slice.bnd -D /var/packages -T 0230000601E8783 -C 1327497125 -d
01/25:13:12:42: /tvbin/TClient: Executing HTTP GET: /tvbin/http_get -U
http://204.176.49.33:8080/static/RM/RM- ... sion.runme -D /var/packages -T 0230000601E8783 -C 1327497125 -d
01/25:13:12:42: /tvbin/TClient: Executing HTTP GET: /tvbin/http_get -U
http://204.176.49.33:8080/static/RM/RM- ... .runme.sig -D /var/packages -T 0230000601E8783 -C 1327497125 -d
01/25:13:12:43: /tvbin/TClient: Executing HTTP GET: /tvbin/http_get -U
http://204.176.49.33:8080/TMS_UK/conten ... 7.slice.gz -D /var/packages -T 0230000601E8783 -C 1327497125 -d
01/25:13:12:45: /tvbin/TClient: Executing HTTP command: /tvbin/http_post /var/log/svclog
http://204.176.49.3:80/tivo-service/mlog.cgi OFF OFF ON
01/25:13:12:46: /tvbin/TClient: no comm log file to post
01/25:13:12:47: /tvbin/TClient: updateStatus: In Progress IM| 41 18 49
01/25:13:12:47: /tvbin/TClient: SendDialupEvent 30 9 IM|41
01/25:13:12:47: /tvbin/TClient: Moving Keys to /Setup
retrying after errTmBackgroundHoldoff ...
retrying after errTmBackgroundHoldoff ...
01/25:13:12:53: /tvbin/TClient: Add key SOFTWARE-199801,1,0x314159265358
01/25:13:12:53: /tvbin/TClient: Found an emergency cleanup file RM-ClearHeadendVersion.runme
01/25:13:13:11: /tvbin/TClient: RM-ClearHeadendVersion.run succeeded:
01/25:13:13:11: /tvbin/TClient: Unbundling /var/packages/PC-DBS-p15174-v1459.slice.bnd
01/25:13:13:11: /tvbin/TClient: Using type-1 Blowfish key SOFTWARE-199801
01/25:13:13:11: /tvbin/TClient: Session key decrypted successfully
01/25:13:13:11: /tvbin/TClient: Session unlocked
01/25:13:13:11: /tvbin/TClient: Decrypting PC-DBS-p15174-v1459.slice.gz.bf to PC-DBS-p15174-v1459.slice.gz
01/25:13:13:11: /tvbin/TClient: Decompressing PC-DBS-p15174-v1459.slice.gz to /var/packages/PC-DBS-p15174-v1459.slice
01/25:13:13:12: /tvbin/TClient: DBLOAD_START_TIME = 1327497192
01/25:13:13:12: /tvbin/TClient: DBLOAD_START_PERCENT = 0
01/25:13:13:12: /tvbin/TClient: DBLOAD_END_PERCENT = 21
01/25:13:13:12: /tvbin/TClient: updateStatus: In Progress IM| 40 17 49
01/25:13:13:12: /tvbin/TClient: SendDialupEvent 30 9 IM|40
01/25:13:13:13: /tvbin/TClient: updateStatus: In Progress IM| 42 19 49
01/25:13:13:13: /tvbin/TClient: SendDialupEvent 30 9 IM|42
01/25:13:13:13: /tvbin/TClient: Final result of signature check is 'No signature'
01/25:13:13:13: /tvbin/TClient: updateStatus: In Progress IM| 42 19 49
01/25:13:13:13: /tvbin/TClient: SendDialupEvent 30 9 IM|42
01/25:13:13:13: /tvbin/TClient: dbload IR-standard-v317.slice...
01/25:13:16:16: /tvbin/TClient: sizeTotal = 222400
01/25:13:16:16: /tvbin/TClient: sizeThisFile = 47609
01/25:13:16:16: /tvbin/TClient: sizeDone = 47609
01/25:13:16:16: /tvbin/TClient: DBLOAD_START_PERCENT = 21
01/25:13:16:16: /tvbin/TClient: DBLOAD_END_PERCENT = 100
01/25:13:16:16: /tvbin/TClient: updateStatus: In Progress IM| 40 17 49
01/25:13:16:16: /tvbin/TClient: SendDialupEvent 30 9 IM|40
01/25:13:16:16: /tvbin/TClient: updateStatus: In Progress IM| 42 19 49
01/25:13:16:16: /tvbin/TClient: SendDialupEvent 30 9 IM|42
01/25:13:16:16: /tvbin/TClient: Final result of signature check is 'No signature'
01/25:13:16:16: /tvbin/TClient: updateStatus: In Progress IM| 42 19 49
01/25:13:16:16: /tvbin/TClient: SendDialupEvent 30 9 IM|42
01/25:13:16:16: /tvbin/TClient: dbload PC-DBS-p15174-v1459.slice...
01/25:13:26:05: /tvbin/TClient: dbload PC-DBS-p15174-v1459.slice: dbload (0x00030007)
01/25:13:26:05: /tvbin/TClient: sizeTotal = 222400
01/25:13:26:05: /tvbin/TClient: sizeThisFile = 174791
01/25:13:26:05: /tvbin/TClient: sizeDone = 222400
01/25:13:26:05: /tvbin/TClient: Moving Keys to /Setup
01/25:13:26:06: /tvbin/TClient: SvrResp::process returning 1
01/25:13:26:06: /tvbin/TClient: backHaulDone is 1
01/25:13:26:06: /tvbin/TClient: registerNewSoftware: getting SwSystemName
01/25:13:26:06: /tvbin/TClient: Failure reading software system none: can't open object (errDbNotFound)
01/25:13:26:06: /tvbin/TClient: updateStatus: Failed IM| 42 19 49
Saving 'IM|19' to result file
01/25:13:26:06: /tvbin/TClient: writing Failed to LastCallStatus
retrying after errTmBackgroundHoldoff ...
01/25:13:26:08: /tvbin/TClient: writing Failed to LastCallStatus
retrying after errTmBackgroundHoldoff ...
01/25:13:26:11: /tvbin/TClient: writing Failed to LastCallStatus
01/25:13:26:11: /tvbin/TClient: SendDialupEvent 30 9 EX|19
01/25:13:26:11: /tvbin/TClient: TClient login: 1
01/25:13:26:11: /tvbin/TClient: TClient backhaulDone: 1