IT02287: "LANFREE DATA BYTES" INCORRECTLY COUNTED IN THE STATISTICS REPORT AFTER A SUCCESSFUL "RESTORE VM"

IBM Tivoli Storage Manager (TSM) Client 7.1.1 Downloads and READMEs
IBM Tivoli Storage Manager Client 6.4.3 Downloads and READMEs

APAR status

• Closed as program error.

Error description

• At the end of a successful RESTORE VM using Tivoli Storage
  Manager Client and/or Tivoli Data Protection for Virtual
  Environments in combination with Tivoli Storage Manager Storage
  Agent, the statistics report shows a wrong bytes count for the
  "LanFree data bytes".
  
  This is an example of a wrong bytes calculation in the final
  statistics report:
  
  ************************
  Total number of objects restored:             1
  Total number of objects failed:               0
  Total number of bytes transferred:         8.49 GB
  LanFree data bytes:                        5.23 MB        <<<<<<
  Data transfer time:                      290.42 sec
  Network data transfer rate:           30,672.92 KB/sec
  Aggregate data transfer rate:         27,278.90 KB/sec
  Elapsed processing time:               00:05:26
  
  ************************
  
  In this example, even if the whole virtual machine restore has
  been performed through the Storage Area Network using Storage
  Agent, only 5.23 MB has been reported as "LanFree data bytes".
  
  Customer/L2 Diagnostics:
  
  A trace collected with the traceflags "service api api_detail
  session vm enter exit" shows that the client calculates only
  bytes from VB_Data and VB_ExtData verbs, while during vm data
  restore the client uses VB_PartialObjDataEnh and
  VB_PartialObjData to transfer the data.
  
  In this specific case the verb 1B00 is PartialObjDataEnh and it
  is used to transport data restoring the vm.
  
  ***************************************************
  ..\..\common\vs\DccVsLanFreeProtocol.cpp(3519):
  ListenOnStorageAgentInbound(000000B8E713C870): Received Server
  verb 1B00.
  ..\..\common\vs\DccVsLanFreeProtocol.cpp(3668):
  ListenOnStorageAgentInbound(000000B8E713C870): Proxying verb
  1B00 to client
  ..\..\common\com\commtsm.cpp( 758): TsmWrite(e7111af8): Placing
  buffer e9495050 on write queue.
  ..\..\common\ut\dsfifo.cpp( 265): fifoQinsert(000000B8E35A6DD0):
  Producer is waiting on consumer
  ..\..\common\ut\dsfifo.cpp( 269): fifoQinsert() Thread 6152
  waiting on queue 000000B8E35A6DD0 .
  vmvddksdk.cpp       (2917): <========= Exiting vddksdkWrite()
  ..\..\common\vm\vmcommonrestvddk.cpp(1403): vmRestoreExtent():
  Writing disk: startSector=786432 numberOfSectors=512
  startByteOffset=402653184, rc=0
  ..\..\common\vm\vmcommonrestvddk.cpp(1239): vmRestoreExtent():
  Sectors left to restore=261632; Restoring 512 sectors
  ..\..\common\vm\vmAPISendData.cpp(2542): =========> Entering
  vmAPISendData::getData()
  ***************************************************
  
  This trace explains why the RESTORE operation ONLY is affected.
  
  Again, the trace shows that if the data comes via data verb, the
  client correctly calculates it adding the size to the
  VsAgntDataBytesRecv:
  
  ***************************************************
   ..\..\common\com\session.cpp(4822):   Length:    81 Code:
  00010005 Type:    <- GetStatsResp
   ..\..\common\cu\cucommon.cpp(4917):
  Contents of verb (0x10005) GetStatsResp, length: 81:
  version             : 0x1
  VsAgntBytesSentHi   : 0
  VsAgntBytesSentLo   : 23
  VsAgntBytesRecvHi   : 0
  VsAgntBytesRecvLo   : 74298 <<<<<<
  VsAgntDataBytesSentHi: 0
  VsAgntDataBytesSentLo: 0
  VsAgntDataBytesRecvHi: 0
  VsAgntDataBytesRecvLo: 74251 <<<<<<
  ***************************************************
  
  If the data comes via PartialObjData the client does not update
  VsAgntDataBytesRecv and statistics are not updated:
  
  ***************************************************
  ..\..\common\com\session.cpp(4822):   Length:    81 Code:
  00010005 Type:    <- GetStatsResp
  ..\..\common\cu\cucommon.cpp(4917):
  Contents of verb (0x10005) GetStatsResp, length: 81:
  version             : 0x1
  VsAgntBytesSentHi   : 0
  VsAgntBytesSentLo   : 97
  VsAgntBytesRecvHi   : 0
  VsAgntBytesRecvLo   : 88084545 <<<<<<
  VsAgntDataBytesSentHi: 0
  VsAgntDataBytesSentLo: 0
  VsAgntDataBytesRecvHi: 0
  VsAgntDataBytesRecvLo: 0 <<<<<<
  ***************************************************
  
  Tivoli Storage Manager Versions Affected:
  Tivoli Storage Manager client 7.1.x on all supported platforms
  
  Initial Impact:
  Low
  
  Additional Keywords:
  restore vm lanfree count bytes wrong lan san backup
  

Local fix

Problem summary

• ****************************************************************
  * USERS AFFECTED: Backup-archive client versions 6.4 and 7.1   *
  *                 running on Microsoft Windows and             *
  *                 Linux x86_64 platforms.                      *
  ****************************************************************
  * PROBLEM DESCRIPTION: See ERROR DESCRIPTION                   *
  ****************************************************************
  * RECOMMENDATION: Apply fixing level when available. ThisPR    *
  *                 problem is currently projected to be fixed   *
  *                 in levels 6.4.3 and 7.1.1. Note that this    *
  *                 is subject to change at the discretion of    *
  *                 IBM.                                         *
  ****************************************************************
  *
  

Problem conclusion

• Lan-Free statistic is now correctly calculated.
  

Temporary fix

Comments

APAR Information

• APAR is sysrouted FROM one or more of the following:

• APAR is sysrouted TO one or more of the following:

Modules/Macros

• DSMAGENT
  

Fix information

• Fixed component name

  TSM CLIENT

• Fixed component ID

  5698ISMCL

Applicable component levels

• R64L PSY

     UP

• R64W PSY

     UP

• R71L PSY

     UP

• R71W PSY

     UP