[petsc-dev] Log_summary truncation behavior for long function names

[Karl Rupp]

 >     Yeah, Karl doesn't know there is a tight limit on the length of 
the strings one can pass in to register so he passed in strings that are 
too long.
>
>     Perhaps Karl could shorten from ViennaCL to VCL?

Karl was (incorrectly) assuming that the only important limit are 32 
characters on functions in the ABI.

While I could just shorten the stage names, but for reasons of 
consistency I will also shorten the function names.

Best regards,
Karli




>
>> On Aug 3, 2015, at 6:40 AM, Patrick Sanan <patrick.sanan at gmail.com> wrote:
>>
>> If long function names (here some related to ViennaCL) are included in the log summary output, the behavior seems wrong, in that the name of the function is truncated, but not to the approriate width (16 characters) .
>>
>> VecAXPBYCZ            10 1.0 4.1723e-05 1.0 5.00e+02 1.0 0.0e+00 0.0e+00 0.0e+00  0 12  0  0  0   0 12  0  0  0    12
>> VecMAXPY              11 1.0 4.6968e-05 1.0 1.30e+03 1.0 0.0e+00 0.0e+00 0.0e+00  0 32  0  0  0   0 32  0  0  0    28
>> VecNormalize          11 1.0 1.6165e-04 1.0 2.89e+02 1.0 0.0e+00 0.0e+00 0.0e+00  0  7  0  0  0   0  7  0  0  0     2
>> VecViennaCLCopyTo      21 1.0 9.5387e-03 1.0 0.00e+00 0.0 0.0e+00 0.0e+00 0.0e+00 11  0  0  0  0  11  0  0  0  0     0
>> VecViennaCLCopyFrom      21 1.0 3.7766e-04 1.0 0.00e+00 0.0 0.0e+00 0.0e+00 0.0e+00  0  0  0  0  0   0  0  0  0  0     0
>> MatMult               21 1.0 1.4230e-02 1.0 5.88e+02 1.0 0.0e+00 0.0e+00 0.0e+00 16 14  0  0  0  16 14  0  0  0     0
>> MatAssemblyBegin       1 1.0 9.5367e-07 1.0 0.00e+00 0.0 0.0e+00 0.0e+00 0.0e+00  0  0  0  0  0   0  0  0  0  0     0
>> MatAssemblyEnd         1 1.0 7.1199e-02 1.0 0.00e+00 0.0 0.0e+00 0.0e+00 0.0e+00 79  0  0  0  0  79  0  0  0  0     0
>> MatView                1 1.0 1.0228e-04 1.0 0.00e+00 0.0 0.0e+00 0.0e+00 0.0e+00  0  0  0  0  0   0  0  0  0  0     0
>> MatViennaCLCopyTo       1 1.0 7.1182e-02 1.0 0.00e+00 0.0 0.0e+00 0.0e+00 0.0e+00 79  0  0  0  0  79  0  0  0  0     0
>> KSPGMRESOrthog        10 1.0 1.5831e-04 1.0 2.14e+03 1.0 0.0e+00 0.0e+00 0.0e+00  0 52  0  0  0   0 52  0  0  0    14
>> KSPSetUp               2 1.0 1.3590e-04 1.0 0.00e+00 0.0 0.0e+00 0.0e+00 0.0e+00  0  0  0  0  0   0  0  0  0  0     0
>>
>> I hacked my local copy to just truncate the names when outputting, as below, but perhaps there is a more elegant way to fix this related to the names of the Log events themselves (I don't know where in the code the names are truncated to begin with).
>>
>>
>> --- a/src/sys/logging/plog.c
>> +++ b/src/sys/logging/plog.c
>> @@ -1473,7 +1473,7 @@ PetscErrorCode  PetscLogView_Default(PetscViewer viewer)
>>         if (numMessages   != 0.0) avgMessLen     = messLen/numMessages;    else avgMessLen     = 0.0;
>>         if (messageLength != 0.0) fracLength     = messLen/messageLength;  else fracLength     = 0.0;
>>         if (numReductions != 0.0) fracReductions = red/numReductions;      else fracReductions = 0.0;
>> -      ierr = PetscFPrintf(comm, fd, "%2d: %15s: %6.4e %5.1f%%  %6.4e %5.1f%%  %5.3e %5.1f%%  %5.3e      %5.1f%%  %5.3e %5.1f%% \n",
>> +      ierr = PetscFPrintf(comm, fd, "%2d: %15.15s: %6.4e %5.1f%%  %6.4e %5.1f%%  %5.3e %5.1f%%  %5.3e      %5.1f%%  %5.3e %5.1f%% \n",
>>                             stage, name, stageTime/size, 100.0*fracTime, flops, 100.0*fracFlops,
>>                             mess, 100.0*fracMessages, avgMessLen, 100.0*fracLength, red, 100.0*fracReductions);CHKERRQ(ierr);
>>       }
>> @@ -1619,7 +1619,7 @@ PetscErrorCode  PetscLogView_Default(PetscViewer viewer)
>>           if (maxt          != 0.0) flopr            = totf/maxt;                  else flopr            = 0.0;
>>           if (fracStageTime > 1.00)  ierr = PetscFPrintf(comm, fd,"Warning -- total time of even greater than time of entire stage -- something is wro
>>           ierr = PetscFPrintf(comm, fd,
>> -          "%-16s %7d%4.1f %5.4e%4.1f %3.2e%4.1f %2.1e %2.1e %2.1e%3.0f%3.0f%3.0f%3.0f%3.0f %3.0f%3.0f%3.0f%3.0f%3.0f %5.0f\n",
>> +          "%-16.16s %7d%4.1f %5.4e%4.1f %3.2e%4.1f %2.1e %2.1e %2.1e%3.0f%3.0f%3.0f%3.0f%3.0f %3.0f%3.0f%3.0f%3.0f%3.0f %5.0f\n",
>>                               name, maxCt, ratCt, maxt, ratt, maxf, ratf, totm, totml, totr,
>>                               100.0*fracTime, 100.0*fracFlops, 100.0*fracMess, 100.0*fracMessLen, 100.0*fracRed,
>>                               100.0*fracStageTime, 100.0*fracStageFlops, 100.0*fracStageMess, 100.0*fracStageMessLen, 100.0*fracStageRed,
>>
>