Script to extract timings for spiky vizzini runs

timewaster.sh

#!/bin/bash

export PATH=/var/vcap/packages/cfdot/bin:$PATH

LOG_DIR=/var/vcap/sys/log
export GARDEN_STDOUT_LOGS="$LOG_DIR/garden/saved.log"
REP_STDOUT_LOGS="$LOG_DIR/rep/saved.log"
VIZZINI_ID="$1"
FORMAT="%s\t\t\t%s\n"

export GARDEN_HANDLE=$(cat $REP_STDOUT_LOGS | grep "$VIZZINI_ID" | grep "container-guid" | tail -1 | jq -r .data[\"container-guid\"])

if [[ -z $GARDEN_HANDLE ]]; then
  echo "Vizzini ID $VIZZINI_ID not found in rep logs"
  exit 1
fi


if ! grep $GARDEN_HANDLE $GARDEN_STDOUT_LOGS > /dev/null; then
  echo "Handle $GARDEN_HANDLE not found garden logs"
  exit 1
fi

function log_time() {
  local message="$1"
  TIME=$(cat $GARDEN_STDOUT_LOGS | grep "$GARDEN_HANDLE" | grep $message | jq -r .timestamp)
  printf "$FORMAT" "$message" "$(date -d @${TIME})"
}
RUNC_FINISHED_TIME=$(cat $GARDEN_STDOUT_LOGS | grep "$GARDEN_HANDLE" | awk '/DINOSAUR-runc-container-started/ {print $1}' | cut -d "=" -f 2 | tr -d '"')

echo "---------------------[${VIZZINI_ID}/${GARDEN_HANDLE}]-------------------------"
log_time "guardian.create.start                                                     "
log_time "image-plugin-create.grootfs.create.groot-creating.starting                "
log_time "image-plugin-create.grootfs.create.groot-creating.ending                  "
log_time "containerizer-create.create.creating                                      "
printf "$FORMAT" "runc-container-started                                                   " "$(date -d ${RUNC_FINISHED_TIME})"
log_time "containerizer-create.create.finished                                      "
log_time "guardian.create.network.started                                           "
log_time "guardian.create.network.finished                                          "
log_time "guardian.create.created                                                   "