account-browser user mix.ipynb

{
 "cells": [
  {
   "cell_type": "markdown",
   "metadata": {},
   "source": [
    "# Single Service JPetStore\n",
    "\n",
    "The single service JPetStore experiments allow running different workloads on the JPetStore with different sets of instrumentation, depending on the variant of JPetStore compiled and installed in the local docker registry.\n",
    "\n",
    "***Add explanation for installing a JPetStore Variant here***\n",
    "\n",
    "Please setup the config file for the experiment. You may use config.template as a template"
   ]
  },
  {
   "cell_type": "code",
   "execution_count": 19,
   "metadata": {
    "scrolled": false
   },
   "outputs": [
    {
     "name": "stdout",
     "output_type": "stream",
     "text": [
      "\u001b[1;37m[info]\u001b[0m --------------------------------------------------------------------\n",
      "\u001b[1;37m[info]\u001b[0m account-browser account-browser\n",
      "\u001b[1;37m[info]\u001b[0m --------------------------------------------------------------------\n",
      "\u001b[1;37m[info]\u001b[0m Cleanup directories\n",
      "\u001b[1;37m[info]\u001b[0m Terminate running single-jpetstores ...\n",
      "\u001b[1;37m[info]\u001b[0m done\n",
      "\u001b[1;37m[info]\u001b[0m Cleanup phantomjs\n",
      "\u001b[1;37m[info]\u001b[0m Cleanup collector...\n",
      "\u001b[1;37m[info]\u001b[0m done\n",
      "\n",
      "\u001b[1;37m[info]\u001b[0m Deploying experiment...\n",
      "\u001b[1;37m[info]\u001b[0m Start collector\n",
      "2018-06-22 14:09:21 DEBUG AbstractServiceMain - Collector\n",
      "2018-06-22 14:09:21 DEBUG DataDumpStage:DataDumpStage-0 - numOpenedInputPorts (inc): 1\n",
      "2018-06-22 14:09:21 DEBUG DataDumpStage - Configuration complete.\n",
      "2018-06-22 14:09:21 ERROR TCPController - Could not parse port for the TCPController, deactivating this option. Received string was: \n",
      "2018-06-22 14:09:21 DEBUG WriterController - Initializing Writer Controller\n",
      "2018-06-22 14:09:21 INFO  StateController - Enabling monitoring\n",
      "2018-06-22 14:09:21 DEBUG MonitoringWriterThread - kieker.monitoring.writer.MonitoringWriterThread is running.\n",
      "2018-06-22 14:09:21 INFO  MonitoringController - Current State of kieker.monitoring (1.14-SNAPSHOT) Status: 'enabled'\n",
      "\tName: 'account-browser'; Hostname: 'stockholm'; experimentID: '1'\n",
      "JMXController: JMX disabled\n",
      "TimeSource: 'kieker.monitoring.timer.SystemNanoTimer'\n",
      "\tTime in nanoseconds (with nanoseconds precision) since Thu Jan 01 01:00:00 CET 1970'\n",
      "ProbeController: disabled\n",
      "WriterController:\n",
      "\tQueue type: class kieker.monitoring.queue.BlockingQueueDecorator\n",
      "\tQueue capacity: 10000\n",
      "\tInsert behavior (a.k.a. QueueFullBehavior): class kieker.monitoring.queue.behavior.BlockOnFailedInsertBehavior\n",
      "\t\tnumBlocked: 0\n",
      "Writer: 'kieker.monitoring.writer.filesystem.FileWriter'\n",
      "\tConfiguration:\n",
      "\t\tkieker.monitoring.writer.filesystem.FileWriter.logFilePoolHandler='kieker.monitoring.writer.filesystem.RotatingLogFilePoolHandler'\n",
      "\t\tkieker.monitoring.writer.filesystem.FileWriter.bufferSize='81920'\n",
      "\t\tkieker.monitoring.writer.filesystem.FileWriter.flush='true'\n",
      "\t\tkieker.monitoring.writer.filesystem.FileWriter.customStoragePath='/home/reiner/Projects/iObserve/experiments/single-jpetstore-clustering-experiment/data/account-browser'\n",
      "\t\tkieker.monitoring.writer.filesystem.FileWriter.charsetName='UTF-8'\n",
      "\t\tkieker.monitoring.writer.filesystem.FileWriter.mapFileHandler='kieker.monitoring.writer.filesystem.TextMapFileHandler'\n",
      "\t\tkieker.monitoring.writer.filesystem.FileWriter.maxLogFiles='-1'\n",
      "\t\tkieker.monitoring.writer.filesystem.FileWriter.maxEntriesInFile='25000'\n",
      "\t\tkieker.monitoring.writer.filesystem.FileWriter.maxLogSize='-1'\n",
      "\t\tkieker.monitoring.writer.filesystem.FileWriter.logStreamHandler='kieker.monitoring.writer.filesystem.TextLogStreamHandler'\n",
      "\n",
      "\tAutomatic assignment of logging timestamps: 'true'\n",
      "Sampling Controller: Periodic Sensor available: Poolsize: '0'; Scheduled Tasks: '0'\n",
      "2018-06-22 14:09:21 DEBUG A3PipeInstantiation - Connected (unsynch) teetime.framework.OutputPort@47f6473 and teetime.framework.InputPort@15975490\n",
      "2018-06-22 14:09:21 DEBUG MultipleConnectionTcpReaderStage - Executing runnable stage...\n",
      "2018-06-22 14:09:21 INFO  Execution - Using scheduler: teetime.framework.scheduling.pushpullmodel.PushPullScheduling@9f70c54\n",
      "2018-06-22 14:09:21 DEBUG AbstractServiceMain - Running collector\n",
      "\u001b[1;37m[info]\u001b[0m Start jpetstore\n",
      "22decee79f3bdf1e017d75bb2098030b573c684573b084063d288d6da6854d67\n",
      "\u001b[1;37m[info]\u001b[0m Service URL is http://172.17.0.2:8080/jpetstore\n",
      "\u001b[1;37m[info]\u001b[0m Connection fails are expected until the service is available.\n",
      "2018-06-22 14:09:34 DEBUG MultipleConnectionTcpReaderStage:MultipleConnectionTcpReaderStage-0 - Connection from /172.17.0.2:35962.\n",
      "\u001b[1;37m[info]\u001b[0m Service ready\n",
      "\n",
      "\u001b[1;37m[info]\u001b[0m Start workload\n",
      "2018-06-22 14:09:37 INFO  WorkloadGenerationMain - Executing workloads: AccountManager, BrowsingUser\n",
      "2018-06-22 14:09:38 DEBUG BehaviorModelRunnable - Running behavior AccountManager\n",
      "2018-06-22 14:09:38 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:09:38 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:09:38 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:09:38 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:09:38 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:09:38 DEBUG TaskRegistry - Filling the registry\n",
      "2018-06-22 14:09:38 DEBUG Reflections - going to scan these urls:\n",
      "jar:file:/home/reiner/Projects/iObserve/experiments/tools/selenium-experiment-workloads-1.0/lib/selenium-experiment-workloads-1.0.jar!/\n",
      "2018-06-22 14:09:38 INFO  Reflections - Reflections took 26 ms to scan 1 urls, producing 5 keys and 26 values \n",
      "2018-06-22 14:09:38 DEBUG Reflections - expanded subtype java.lang.Comparable -> java.lang.Enum\n",
      "2018-06-22 14:09:38 DEBUG Reflections - expanded subtype java.io.Serializable -> java.lang.Enum\n",
      "2018-06-22 14:09:38 DEBUG ComposedBehavior - execute AccountManager\n",
      "2018-06-22 14:09:38 DEBUG ComposedBehavior - part AccountManager\n",
      "2018-06-22 14:09:38 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:09:38 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:09:41 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:09:41 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:09:41 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:09:41 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:09:41 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:09:47 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:09:47 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:09:47 DEBUG BehaviorModelRunnable - Running behavior AccountManager\n",
      "2018-06-22 14:09:47 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:09:47 DEBUG ComposedBehavior - execute AccountManager\n",
      "2018-06-22 14:09:47 DEBUG ComposedBehavior - part AccountManager\n",
      "2018-06-22 14:09:47 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:09:47 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:09:47 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:09:47 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:09:52 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:09:52 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:09:52 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:09:52 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:09:52 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:09:57 DEBUG BehaviorModelRunnable - Running behavior AccountManager\n",
      "2018-06-22 14:09:57 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:09:57 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:09:57 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:09:57 DEBUG ComposedBehavior - execute AccountManager\n",
      "2018-06-22 14:09:57 DEBUG ComposedBehavior - part AccountManager\n",
      "2018-06-22 14:09:57 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:09:57 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:09:57 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:09:57 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:10:02 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:10:02 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:10:02 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:10:02 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:10:02 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:10:07 DEBUG BehaviorModelRunnable - Running behavior AccountManager\n",
      "2018-06-22 14:10:07 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:10:07 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:10:07 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:10:07 DEBUG ComposedBehavior - execute AccountManager\n",
      "2018-06-22 14:10:07 DEBUG ComposedBehavior - part AccountManager\n",
      "2018-06-22 14:10:07 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:10:07 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:10:07 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:10:07 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:10:12 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:10:12 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:10:12 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:10:12 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:10:12 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:10:17 DEBUG BehaviorModelRunnable - Running behavior AccountManager\n",
      "2018-06-22 14:10:17 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:10:17 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:10:17 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:10:17 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:10:17 DEBUG ComposedBehavior - execute AccountManager\n",
      "2018-06-22 14:10:17 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:10:17 DEBUG ComposedBehavior - part AccountManager\n",
      "2018-06-22 14:10:17 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:10:17 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:10:22 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:10:22 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:10:22 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:10:22 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:10:22 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:10:27 DEBUG BehaviorModelRunnable - Running behavior AccountManager\n",
      "2018-06-22 14:10:27 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:10:27 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:10:27 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:10:27 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:10:27 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:10:27 DEBUG ComposedBehavior - execute AccountManager\n",
      "2018-06-22 14:10:27 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:10:27 DEBUG ComposedBehavior - part AccountManager\n",
      "2018-06-22 14:10:27 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:10:32 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:10:32 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:10:32 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:10:32 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:10:32 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:10:37 DEBUG BehaviorModelRunnable - Running behavior AccountManager\n",
      "2018-06-22 14:10:37 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:10:37 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:10:37 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:10:37 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:10:37 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:10:37 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:10:37 DEBUG ComposedBehavior - execute AccountManager\n",
      "2018-06-22 14:10:37 DEBUG ComposedBehavior - part AccountManager\n",
      "2018-06-22 14:10:37 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:10:42 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:10:42 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:10:42 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:10:42 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:10:42 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:10:47 DEBUG BehaviorModelRunnable - Running behavior AccountManager\n",
      "2018-06-22 14:10:47 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:10:47 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:10:47 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:10:47 DEBUG ComposedBehavior - execute AccountManager\n",
      "2018-06-22 14:10:47 DEBUG ComposedBehavior - part AccountManager\n",
      "2018-06-22 14:10:47 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:10:47 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:10:47 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:10:47 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:10:52 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:10:52 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:10:52 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:10:52 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:10:52 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:10:57 DEBUG BehaviorModelRunnable - Running behavior AccountManager\n",
      "2018-06-22 14:10:57 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:10:57 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:10:57 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:10:57 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:10:57 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:10:57 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:10:57 DEBUG ComposedBehavior - execute AccountManager\n",
      "2018-06-22 14:10:57 DEBUG ComposedBehavior - part AccountManager\n",
      "2018-06-22 14:10:57 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:11:02 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:11:02 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:11:02 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:11:02 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:11:02 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:11:07 DEBUG BehaviorModelRunnable - Running behavior AccountManager\n",
      "2018-06-22 14:11:07 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:11:07 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:11:07 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:11:07 DEBUG ComposedBehavior - execute AccountManager\n",
      "2018-06-22 14:11:07 DEBUG ComposedBehavior - part AccountManager\n",
      "2018-06-22 14:11:07 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:11:07 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:11:07 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:11:07 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:11:12 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:11:12 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:11:12 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:11:12 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:11:12 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:11:17 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:11:17 DEBUG BehaviorModelRunnable - Running behavior AccountManager\n",
      "2018-06-22 14:11:17 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:11:17 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:11:17 DEBUG ComposedBehavior - execute AccountManager\n",
      "2018-06-22 14:11:17 DEBUG ComposedBehavior - part AccountManager\n",
      "2018-06-22 14:11:17 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:11:17 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:11:17 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:11:17 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:11:22 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:11:22 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:11:22 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:11:22 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:11:22 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:11:27 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:11:27 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:11:27 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:11:27 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:11:27 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:11:32 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:11:32 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:11:32 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:11:32 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:11:32 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:11:37 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:11:37 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:11:37 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:11:37 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:11:37 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:11:42 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:11:42 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:11:42 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:11:42 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:11:42 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:11:47 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:11:47 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:11:47 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:11:47 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:11:47 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:11:52 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:11:52 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:11:52 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:11:52 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:11:52 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:11:57 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:11:57 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:11:57 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:11:57 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:11:57 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:12:02 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:12:02 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:12:02 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:12:02 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:12:02 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:12:07 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:12:07 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:12:07 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:12:07 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:12:07 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:12:12 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:12:12 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:12:12 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:12:12 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:12:12 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:12:17 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:12:17 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:12:17 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:12:17 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:12:17 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:12:22 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:12:22 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:12:22 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:12:22 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:12:22 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:12:27 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:12:27 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:12:27 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:12:27 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:12:27 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:12:32 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:12:32 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:12:32 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:12:32 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:12:32 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:12:37 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:12:37 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:12:37 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:12:37 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:12:37 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:12:42 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:12:42 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:12:42 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:12:42 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:12:42 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:12:47 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:12:47 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:12:47 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:12:47 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:12:47 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:12:52 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:12:52 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:12:52 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:12:52 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:12:52 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:12:57 DEBUG BehaviorModelRunnable - Running behavior BrowsingUser\n",
      "2018-06-22 14:12:57 DEBUG ComposedBehavior - delete all cookies -> new session\n",
      "2018-06-22 14:12:57 DEBUG ComposedBehavior - execute BrowsingUser\n",
      "2018-06-22 14:12:57 DEBUG ComposedBehavior - part BrowsingUser\n",
      "2018-06-22 14:12:57 DEBUG ComposedBehavior - delay 2.0\n",
      "2018-06-22 14:13:48 DEBUG WorkloadGenerationMain - Run complete. Waiting for executor shutdown.\n",
      "2018-06-22 14:13:48 DEBUG WorkloadGenerationMain - Run complete. Waiting for web driver termination.\n",
      "2018-06-22 14:13:48 INFO  WorkloadGenerationMain - AccountManager had 11 executions\n",
      "2018-06-22 14:13:48 INFO  WorkloadGenerationMain - BrowsingUser had 41 executions\n",
      "2018-06-22 14:13:48 INFO  WorkloadGenerationMain - Workload execution finished.\n",
      "\u001b[1;37m[info]\u001b[0m Undeploying experiment.\n",
      "\u001b[1;37m[info]\u001b[0m Terminate running single-jpetstores ...\n",
      "2018-06-22 14:14:08 DEBUG MultipleConnectionTcpReaderStage:MultipleConnectionTcpReaderStage-0 - Socket closed: /172.17.0.2:35962\n",
      "22decee79f3b\n",
      "22decee79f3b\n",
      "\u001b[1;37m[info]\u001b[0m done\n",
      "2018-06-22 14:14:08 INFO  MonitoringController - ShutdownHook notifies controller to initiate shutdown.\n",
      "2018-06-22 14:14:08 INFO  MonitoringController - Terminating monitoring...\n",
      "2018-06-22 14:14:08 INFO  MultipleConnectionTcpReaderStage:MultipleConnectionTcpReaderStage-0 - External shutdown called\n",
      "2018-06-22 14:14:08 INFO  MonitoringController - Shutting down Monitoring Controller (account-browser)\n",
      "2018-06-22 14:14:08 DEBUG ProbeController - Shutting down Probe Controller\n",
      "2018-06-22 14:14:08 DEBUG TimeSourceController - Shutting down TimeSource Controller\n",
      "2018-06-22 14:14:08 DEBUG MultipleConnectionTcpReaderStage - Finished runnable stage. (MultipleConnectionTcpReaderStage-0)\n",
      "2018-06-22 14:14:08 DEBUG WriterController - Shutting down Writer Controller\n",
      "2018-06-22 14:14:08 DEBUG SamplingController - Shutting down Sampling Controller\n",
      "2018-06-22 14:14:08 DEBUG MonitoringWriterThread - kieker.monitoring.writer.MonitoringWriterThread has finished.\n",
      "2018-06-22 14:14:08 DEBUG StateController - Shutting down State Controller\n",
      "\u001b[1;37m[info]\u001b[0m Experiment complete.\n"
     ]
    },
    {
     "name": "stderr",
     "output_type": "stream",
     "text": [
      "phantomjs: no process found\n",
      "curl: (7) Failed to connect to 172.17.0.2 port 8080: Connection refused\n",
      "Jun 22, 2018 2:09:37 PM org.openqa.selenium.phantomjs.PhantomJSDriverService <init>\n",
      "INFO: executable: /home/reiner/Projects/iObserve/experiments/single-jpetstore-clustering-experiment/../tools/phantomjs-2.1.1-linux-x86_64/bin/phantomjs\n",
      "Jun 22, 2018 2:09:37 PM org.openqa.selenium.phantomjs.PhantomJSDriverService <init>\n",
      "INFO: port: 5827\n",
      "Jun 22, 2018 2:09:37 PM org.openqa.selenium.phantomjs.PhantomJSDriverService <init>\n",
      "INFO: arguments: [--web-security=no, --ignore-ssl-errors=yes, --webdriver=5827, --webdriver-logfile=/home/reiner/Projects/iObserve/experiments/single-jpetstore-clustering-experiment/phantomjsdriver.log]\n",
      "Jun 22, 2018 2:09:37 PM org.openqa.selenium.phantomjs.PhantomJSDriverService <init>\n",
      "INFO: environment: {}\n",
      "[INFO  - 2018-06-22T12:09:37.660Z] GhostDriver - Main - running on port 5827\n",
      "[INFO  - 2018-06-22T12:09:37.934Z] Session [22758cc0-7615-11e8-a0fa-67fa4d3b5411] - page.settings - {\"XSSAuditingEnabled\":false,\"javascriptCanCloseWindows\":true,\"javascriptCanOpenWindows\":true,\"javascriptEnabled\":true,\"loadImages\":true,\"localToRemoteUrlAccessEnabled\":false,\"userAgent\":\"Mozilla/5.0 (Unknown; Linux x86_64) AppleWebKit/538.1 (KHTML, like Gecko) PhantomJS/2.1.1 Safari/538.1\",\"webSecurityEnabled\":false}\n",
      "[INFO  - 2018-06-22T12:09:37.934Z] Session [22758cc0-7615-11e8-a0fa-67fa4d3b5411] - page.customHeaders:  - {}\n",
      "[INFO  - 2018-06-22T12:09:37.934Z] Session [22758cc0-7615-11e8-a0fa-67fa4d3b5411] - Session.negotiatedCapabilities - {\"browserName\":\"phantomjs\",\"version\":\"2.1.1\",\"driverName\":\"ghostdriver\",\"driverVersion\":\"1.2.0\",\"platform\":\"linux-unknown-64bit\",\"javascriptEnabled\":true,\"takesScreenshot\":false,\"handlesAlerts\":false,\"databaseEnabled\":false,\"locationContextEnabled\":false,\"applicationCacheEnabled\":false,\"browserConnectionEnabled\":false,\"cssSelectorsEnabled\":true,\"webStorageEnabled\":false,\"rotatable\":false,\"acceptSslCerts\":false,\"nativeEvents\":true,\"proxy\":{\"proxyType\":\"direct\"}}\n",
      "[INFO  - 2018-06-22T12:09:37.935Z] SessionManagerReqHand - _postNewSessionCommand - New Session Created: 22758cc0-7615-11e8-a0fa-67fa4d3b5411\n",
      "Jun 22, 2018 2:09:37 PM org.openqa.selenium.remote.ProtocolHandshake createSession\n",
      "INFO: Detected dialect: OSS\n",
      "[ERROR - 2018-06-22T12:09:39.258Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669379216\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:09:39.259Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669379225\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:09:42.147Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669382115\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:09:48.148Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669388115\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:09:48.150Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669388119\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:09:53.148Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669393115\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:09:58.157Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669398118\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:09:58.158Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669398120\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:10:03.155Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669403119\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:10:08.156Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669408118\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:10:08.157Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669408120\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:10:13.157Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669413118\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:10:18.155Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669418119\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:10:18.157Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669418120\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:10:23.155Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669423118\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:10:28.156Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669428119\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:10:28.157Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669428120\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:10:33.155Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669433118\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:10:38.155Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669438118\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:10:38.157Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669438120\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:10:43.151Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669443118\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:10:48.155Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669448119\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:10:48.157Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669448120\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:10:53.152Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669453118\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:10:58.155Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669458118\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:10:58.158Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669458120\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:11:03.158Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669463119\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:11:08.162Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669468123\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:11:08.164Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669468125\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:11:13.158Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669473122\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:11:18.158Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669478122\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:11:18.159Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669478124\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:11:23.159Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669483122\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:11:28.157Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669488122\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:11:33.157Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669493122\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:11:38.158Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669498122\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:11:43.158Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669503122\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:11:48.158Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669508122\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:11:53.160Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669513126\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:11:58.161Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669518126\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:12:03.161Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669523126\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:12:08.160Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669528126\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:12:13.161Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669533126\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:12:18.160Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669538127\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:12:23.160Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669543126\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:12:28.161Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669548126\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:12:33.161Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669553126\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:12:38.160Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669558126\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:12:43.164Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669563130\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:12:48.165Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669568130\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:12:53.164Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669573126\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[ERROR - 2018-06-22T12:12:58.165Z] WebElementLocator - _handleLocateCommand - Element(s) NOT Found: GAVE UP. Search Stop Time: 1529669578130\n",
      "\n",
      "  phantomjs://platform/console++.js:263 in error\n",
      "[INFO  - 2018-06-22T12:13:48.081Z] ShutdownReqHand - _handle - About to shutdown\n",
      "phantomjs: no process found\n"
     ]
    }
   ],
   "source": [
    "%%bash\n",
    "bash ./execute-observation.sh workloads/account-browser.yaml account-browser"
   ]
  },
  {
   "cell_type": "code",
   "execution_count": 20,
   "metadata": {},
   "outputs": [
    {
     "name": "stdout",
     "output_type": "stream",
     "text": [
      "data:\n",
      "total 4\n",
      "drwxr-xr-x 3 reiner reiner 4096 Jun 22 14:09 account-browser\n",
      "\n",
      "data/account-browser:\n",
      "total 4\n",
      "drwxr-xr-x 2 reiner reiner 4096 Jun 22 14:09 kieker-20180622-120921-105084774778130-UTC--\n",
      "\n",
      "data/account-browser/kieker-20180622-120921-105084774778130-UTC--:\n",
      "total 8\n",
      "-rw-r--r-- 1 reiner reiner 1633 Jun 22 14:09 kieker-20180622-120921907-UTC-001.dat\n",
      "-rw-r--r-- 1 reiner reiner  363 Jun 22 14:09 kieker.map\n"
     ]
    }
   ],
   "source": [
    "%%bash\n",
    "ls -lR data"
   ]
  },
  {
   "cell_type": "markdown",
   "metadata": {},
   "source": [
    "## Analysis of Observed Data\n",
    "\n",
    "Run one behavior analysis."
   ]
  },
  {
   "cell_type": "code",
   "execution_count": 21,
   "metadata": {},
   "outputs": [
    {
     "name": "stdout",
     "output_type": "stream",
     "text": [
      "/home/reiner/Projects/iObserve/experiments/single-jpetstore-clustering-experiment/data/account-browser\n",
      "\u001b[1;37m[info]\u001b[0m Kieker directories /home/reiner/Projects/iObserve/experiments/single-jpetstore-clustering-experiment/data/account-browser/kieker-20180622-120921-105084774778130-UTC--\n",
      "\u001b[1;37m[info]\u001b[0m ------------------------\n",
      "\u001b[1;37m[info]\u001b[0m Run analysis\n",
      "\u001b[1;37m[info]\u001b[0m ------------------------\n",
      "2018-06-22 14:14:45 DEBUG AbstractServiceMain - Analysis Service\n",
      "2018-06-22 14:14:45 DEBUG DynamicEventDispatcher:DynamicEventDispatcher-0 - numOpenedInputPorts (inc): 1\n",
      "2018-06-22 14:14:45 DEBUG ClassNameRegistryCreationFilter:ClassNameRegistryCreationFilter-0 - numOpenedInputPorts (inc): 1\n",
      "2018-06-22 14:14:45 DEBUG Directory2FilesFilter:Directory2FilesFilter-0 - numOpenedInputPorts (inc): 1\n",
      "2018-06-22 14:14:45 DEBUG FileExtensionSwitch:FileExtensionSwitch-0 - numOpenedInputPorts (inc): 1\n",
      "2018-06-22 14:14:45 DEBUG DatFileToRecordStage:DatFileToRecordStage-0 - numOpenedInputPorts (inc): 1\n",
      "2018-06-22 14:14:45 DEBUG BinaryFile2RecordFilter:BinaryFile2RecordFilter-0 - numOpenedInputPorts (inc): 1\n",
      "2018-06-22 14:14:45 DEBUG BinaryFile2RecordFilter:BinaryFile2RecordFilter-1 - numOpenedInputPorts (inc): 1\n",
      "2018-06-22 14:14:45 DEBUG Merger:Merger-0 - numOpenedInputPorts (inc): 1\n",
      "2018-06-22 14:14:45 DEBUG Merger:Merger-0 - numOpenedInputPorts (inc): 2\n",
      "2018-06-22 14:14:45 DEBUG Merger:Merger-0 - numOpenedInputPorts (inc): 3\n",
      "2018-06-22 14:14:45 DEBUG TranslateCallOperationEventsStage:TranslateCallOperationEventsStage-0 - numOpenedInputPorts (inc): 1\n",
      "2018-06-22 14:14:45 DEBUG TraceReconstructionFilter:TraceReconstructionFilter-0 - numOpenedInputPorts (inc): 1\n",
      "2018-06-22 14:14:45 WARN  AnalysisConfiguration - Configuration for dataflow analysis missing.\n",
      "2018-06-22 14:14:45 INFO  A3PipeInstantiation - Unconnected port teetime.framework.OutputPort@a67c67e in stage DynamicEventDispatcher-0\n",
      "2018-06-22 14:14:45 DEBUG A3PipeInstantiation - Connected (unsynch) teetime.framework.OutputPort@20398b7c and teetime.framework.InputPort@6fc6f14e\n",
      "2018-06-22 14:14:45 DEBUG A3PipeInstantiation - Connected (unsynch) teetime.framework.OutputPort@3632be31 and teetime.framework.InputPort@5abca1e0\n",
      "2018-06-22 14:14:45 INFO  A3PipeInstantiation - Unconnected port teetime.framework.OutputPort@2286778 in stage TraceReconstructionFilter-0\n",
      "2018-06-22 14:14:45 INFO  A3PipeInstantiation - Unconnected port teetime.framework.OutputPort@4e9ba398 in stage TraceReconstructionFilter-0\n",
      "2018-06-22 14:14:45 DEBUG A3PipeInstantiation - Connected (unsynch) teetime.framework.OutputPort@3108bc and teetime.framework.InputPort@370736d9\n",
      "2018-06-22 14:14:45 DEBUG A3PipeInstantiation - Connected (unsynch) teetime.framework.OutputPort@6b09bb57 and teetime.framework.InputPort@6536e911\n",
      "2018-06-22 14:14:45 DEBUG A3PipeInstantiation - Connected (unsynch) teetime.framework.OutputPort@3e9b1010 and teetime.framework.InputPort@6c3708b3\n",
      "2018-06-22 14:14:45 INFO  A3PipeInstantiation - Unconnected port teetime.framework.OutputPort@6f1fba17 in stage FileExtensionSwitch-0\n",
      "2018-06-22 14:14:45 DEBUG A3PipeInstantiation - Connected (unsynch) teetime.framework.OutputPort@335eadca and teetime.framework.InputPort@210366b4\n",
      "2018-06-22 14:14:45 DEBUG A3PipeInstantiation - Connected (unsynch) teetime.framework.OutputPort@2b2948e2 and teetime.framework.InputPort@6ddf90b0\n",
      "2018-06-22 14:14:45 DEBUG A3PipeInstantiation - Connected (unsynch) teetime.framework.OutputPort@5a8e6209 and teetime.framework.InputPort@4b4523f8\n",
      "2018-06-22 14:14:45 DEBUG A3PipeInstantiation - Connected (unsynch) teetime.framework.OutputPort@369f73a2 and teetime.framework.InputPort@1f28c152\n",
      "2018-06-22 14:14:45 DEBUG A3PipeInstantiation - Connected (unsynch) teetime.framework.OutputPort@3a5ed7a6 and teetime.framework.InputPort@6325a3ee\n",
      "2018-06-22 14:14:45 DEBUG A3PipeInstantiation - Connected (unsynch) teetime.framework.OutputPort@2b9627bc and teetime.framework.InputPort@65e2dbf3\n",
      "2018-06-22 14:14:45 DEBUG A3PipeInstantiation - Connected (unsynch) teetime.framework.OutputPort@7b49cea0 and teetime.framework.InputPort@887af79\n",
      "2018-06-22 14:14:45 DEBUG InitialElementProducer - Executing runnable stage...\n",
      "2018-06-22 14:14:45 INFO  Execution - Using scheduler: teetime.framework.scheduling.pushpullmodel.PushPullScheduling@49fc609f\n",
      "2018-06-22 14:14:45 WARN  DynamicEventDispatcher:DynamicEventDispatcher-0 - Received more than one signal - teetime.framework.signal.ValidatingSignal@3b084709 - from input port: teetime.framework.InputPort@370736d9\n",
      "2018-06-22 14:14:45 WARN  DynamicEventDispatcher:DynamicEventDispatcher-0 - Received more than one signal - teetime.framework.signal.ValidatingSignal@3b084709 - from input port: teetime.framework.InputPort@370736d9\n",
      "2018-06-22 14:14:45 DEBUG AbstractServiceMain - Running analysis\n",
      "2018-06-22 14:14:45 WARN  DynamicEventDispatcher - Configuration error: New unknown event type kieker.common.record.misc.KiekerMetadataRecord.\n",
      "2018-06-22 14:14:45 WARN  DynamicEventDispatcher - Configuration error: New unknown event type org.iobserve.common.record.Privacy_ServletDeployedEvent.\n",
      "2018-06-22 14:14:45 INFO  DynamicEventDispatcher - Records processed in total 12.\n",
      "2018-06-22 14:14:45 INFO  TraceReconstructor - Incomplete traces 0\n",
      "2018-06-22 14:14:45 DEBUG InitialElementProducer - Finished runnable stage. (InitialElementProducer-0)\n",
      "2018-06-22 14:14:45 DEBUG AbstractServiceMain - Done\n",
      "\u001b[1;37m[info]\u001b[0m Analysis complete.\n"
     ]
    }
   ],
   "source": [
    "%%bash\n",
    "./execute-analysis.sh xmeans raw account-browser"
   ]
  },
  {
   "cell_type": "code",
   "execution_count": 22,
   "metadata": {},
   "outputs": [
    {
     "name": "stdout",
     "output_type": "stream",
     "text": [
      "analysis:\n",
      "aggregatedDeploymentCallTree.dot\n",
      "aggregatedDeploymentCallTree.pdf\n",
      "aggregatedDeploymentCallTree.svg\n",
      "assemblyComponentDependencyGraph.dot\n",
      "assemblyComponentDependencyGraph.pdf\n",
      "assemblyComponentDependencyGraph.svg\n",
      "containerDependencyGraph.dot\n",
      "containerDependencyGraph.pdf\n",
      "containerDependencyGraph.svg\n",
      "deploymentComponentDependencyGraph.dot\n",
      "deploymentComponentDependencyGraph.pdf\n",
      "deploymentComponentDependencyGraph.svg\n",
      "system-entities.html\n",
      "traceAnalysis.kax\n"
     ]
    }
   ],
   "source": [
    "%%bash\n",
    "ls -R analysis"
   ]
  },
  {
   "cell_type": "code",
   "execution_count": 25,
   "metadata": {},
   "outputs": [
    {
     "data": {
      "text/html": [
       "<img src=\"analysis/deploymentComponentDependencyGraph.svg\"/>"
      ],
      "text/plain": [
       "<IPython.core.display.Image object>"
      ]
     },
     "execution_count": 25,
     "metadata": {},
     "output_type": "execute_result"
    }
   ],
   "source": [
    "from IPython.display import Image\n",
    "from IPython.core.display import HTML \n",
    "Image(url= \"analysis/aggregatedDeploymentCallTree.svg\")"
   ]
  },
  {
   "cell_type": "code",
   "execution_count": 26,
   "metadata": {},
   "outputs": [
    {
     "data": {
      "text/html": [
       "<img src=\"analysis/assemblyComponentDependencyGraph.svg\"/>"
      ],
      "text/plain": [
       "<IPython.core.display.Image object>"
      ]
     },
     "execution_count": 26,
     "metadata": {},
     "output_type": "execute_result"
    }
   ],
   "source": [
    "from IPython.display import Image\n",
    "from IPython.core.display import HTML \n",
    "Image(url= \"analysis/assemblyComponentDependencyGraph.svg\")"
   ]
  },
  {
   "cell_type": "code",
   "execution_count": 27,
   "metadata": {},
   "outputs": [
    {
     "data": {
      "text/html": [
       "<img src=\"analysis/containerDependencyGraph.svg\"/>"
      ],
      "text/plain": [
       "<IPython.core.display.Image object>"
      ]
     },
     "execution_count": 27,
     "metadata": {},
     "output_type": "execute_result"
    }
   ],
   "source": [
    "from IPython.display import Image\n",
    "from IPython.core.display import HTML \n",
    "Image(url= \"analysis/containerDependencyGraph.svg\")"
   ]
  },
  {
   "cell_type": "code",
   "execution_count": 28,
   "metadata": {},
   "outputs": [
    {
     "data": {
      "text/html": [
       "<img src=\"analysis/deploymentComponentDependencyGraph.svg\"/>"
      ],
      "text/plain": [
       "<IPython.core.display.Image object>"
      ]
     },
     "execution_count": 28,
     "metadata": {},
     "output_type": "execute_result"
    }
   ],
   "source": [
    "from IPython.display import Image\n",
    "from IPython.core.display import HTML \n",
    "Image(url= \"analysis/deploymentComponentDependencyGraph.svg\")"
   ]
  },
  {
   "cell_type": "code",
   "execution_count": null,
   "metadata": {},
   "outputs": [],
   "source": []
  }
 ],
 "metadata": {
  "kernelspec": {
   "display_name": "Python 3",
   "language": "python",
   "name": "python3"
  },
  "language_info": {
   "codemirror_mode": {
    "name": "ipython",
    "version": 3
   },
   "file_extension": ".py",
   "mimetype": "text/x-python",
   "name": "python",
   "nbconvert_exporter": "python",
   "pygments_lexer": "ipython3",
   "version": "3.6.5"
  }
 },
 "nbformat": 4,
 "nbformat_minor": 2
}