python3 -u test.py
RUN ./ol new -p=/tmp/test-cluster
b'Init OL dir at /tmp/test-cluster\nCreate lambda base at /tmp/test-cluster/lambda (may take several minutes)\nWorking Directory: /tmp/test-cluster\n\nWorker Defaults: \n{\n\t"registry": "/tmp/test-cluster/registry",\n\t"registry_cache_ms": 5000,\n\t"sandbox": "sock",\n\t"cluster_name": "/tmp/test-cluster",\n\t"pip_mirror": "",\n\t"Pkgs_dir": "/tmp/test-cluster/lambda/packages",\n\t"max_runners": 0,\n\t"handler_cache_mb": 239,\n\t"import_cache_mb": 239,\n\t"server_mode": "lambda",\n\t"worker_dir": "/tmp/test-cluster/worker",\n\t"SOCK_base_path": "/tmp/test-cluster/lambda",\n\t"worker_port": "5000",\n\t"Skip_pull_existing": false,\n\t"sandbox_config": {\n\t\t"processes": 10\n\t},\n\t"benchmark_log": "",\n\t"timing": false,\n\t"startup_pkgs": null,\n\t"docker_runtime": "",\n\t"sock_cgroups": {\n\t\t"max_procs": 10,\n\t\t"max_mem_mb": 50\n\t}\n}\n\nYou may modify the defaults here: /tmp/test-cluster/config.json\n\nYou may now start a server using the "ol worker" command\n'
PUSH conf: {'server_mode': 'sock'}
========================================
sock_churn
========================================
RUN ./ol worker -p=/tmp/test-cluster --detach
b'using existing OL directory at /tmp/test-cluster\nStarting worker: pid=7300, port=5000, log=/tmp/test-cluster/worker.out\nready\n'
RUN ./ol kill -p=/tmp/test-cluster
b'Kill worker process with PID 7300\n'
{
  "test": "sock_churn",
  "params": {
    "fork": true,
    "seconds": 15,
    "procs": 1,
    "baseline": 0
  },
  "pass": true,
  "seconds": 17.074936866760254,
  "total_seconds": 17.43089199066162,
  "stats": {
    "sandboxes_per_sec": 47.266666666666666
  },
  "ol-stats": {
    "Create().ms.avg": 12,
    "Create().ms.cnt": 710,
    "Create().ms.sum": 8875,
    "Destroy().ms.avg": 2,
    "Destroy().ms.cnt": 709,
    "Destroy().ms.sum": 2062,
    "acquire-cgroup.ms.avg": 0,
    "acquire-cgroup.ms.cnt": 710,
    "acquire-cgroup.ms.sum": 0,
    "acquire-mem.ms.avg": 0,
    "acquire-mem.ms.cnt": 710,
    "acquire-mem.ms.sum": 0,
    "fork().ms.avg": 6,
    "fork().ms.cnt": 709,
    "fork().ms.sum": 4744,
    "fork-proc.ms.avg": 6,
    "fork-proc.ms.cnt": 709,
    "fork-proc.ms.sum": 4772,
    "forkRequest.ms.avg": 5,
    "forkRequest.ms.cnt": 709,
    "forkRequest.ms.sum": 4222,
    "fresh-proc.ms.avg": 1940,
    "fresh-proc.ms.cnt": 1,
    "fresh-proc.ms.sum": 1940,
    "make-proc.ms.avg": 9,
    "make-proc.ms.cnt": 710,
    "make-proc.ms.sum": 6713,
    "make-root-fs.ms.avg": 2,
    "make-root-fs.ms.cnt": 710,
    "make-root-fs.ms.sum": 1791,
    "move-to-cg-after-fork.ms.avg": 0,
    "move-to-cg-after-fork.ms.cnt": 709,
    "move-to-cg-after-fork.ms.sum": 198
  },
  "conf": {
    "startup_pkgs": null,
    "docker_runtime": "",
    "Pkgs_dir": "/tmp/test-cluster/lambda/packages",
    "pip_mirror": "",
    "server_mode": "sock",
    "registry_cache_ms": 5000,
    "handler_cache_mb": 239,
    "max_runners": 0,
    "worker_port": "5000",
    "worker_dir": "/tmp/test-cluster/worker",
    "import_cache_mb": 239,
    "sandbox_config": {
      "processes": 10
    },
    "sandbox": "sock",
    "Skip_pull_existing": false,
    "cluster_name": "/tmp/test-cluster",
    "benchmark_log": "",
    "registry": "/tmp/test-cluster/registry",
    "timing": false,
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "SOCK_base_path": "/tmp/test-cluster/lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 15:23:38 remove /sys/fs/cgroup/devices/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:23:38 remove /sys/fs/cgroup/freezer/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:23:38 remove /sys/fs/cgroup/hugetlb/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:23:38 remove /sys/fs/cgroup/memory/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:23:38 remove /sys/fs/cgroup/perf_event/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:23:38 remove /sys/fs/cgroup/systemd/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:23:38 remove /sys/fs/cgroup/pids/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:23:38 remove worker.pid",
    "2019/06/28 15:23:38 exiting",
    ""
  ]
}
========================================
sock_churn
========================================
RUN ./ol worker -p=/tmp/test-cluster --detach
b'using existing OL directory at /tmp/test-cluster\nStarting worker: pid=10169, port=5000, log=/tmp/test-cluster/worker.out\nready\n'
RUN ./ol kill -p=/tmp/test-cluster
b'Kill worker process with PID 10169\n'
{
  "test": "sock_churn",
  "params": {
    "fork": true,
    "seconds": 15,
    "procs": 15,
    "baseline": 0
  },
  "pass": true,
  "seconds": 15.541294813156128,
  "total_seconds": 15.781873226165771,
  "stats": {
    "sandboxes_per_sec": 47.06666666666667
  },
  "ol-stats": {
    "Create().ms.avg": 143,
    "Create().ms.cnt": 707,
    "Create().ms.sum": 101135,
    "Destroy().ms.avg": 12,
    "Destroy().ms.cnt": 706,
    "Destroy().ms.sum": 8531,
    "acquire-cgroup.ms.avg": 0,
    "acquire-cgroup.ms.cnt": 707,
    "acquire-cgroup.ms.sum": 0,
    "acquire-mem.ms.avg": 98,
    "acquire-mem.ms.cnt": 707,
    "acquire-mem.ms.sum": 69522,
    "fork().ms.avg": 39,
    "fork().ms.cnt": 706,
    "fork().ms.sum": 28058,
    "fork-proc.ms.avg": 39,
    "fork-proc.ms.cnt": 706,
    "fork-proc.ms.sum": 28097,
    "forkRequest.ms.avg": 10,
    "forkRequest.ms.cnt": 706,
    "forkRequest.ms.sum": 7321,
    "fresh-proc.ms.avg": 162,
    "fresh-proc.ms.cnt": 1,
    "fresh-proc.ms.sum": 162,
    "make-proc.ms.avg": 39,
    "make-proc.ms.cnt": 707,
    "make-proc.ms.sum": 28261,
    "make-root-fs.ms.avg": 3,
    "make-root-fs.ms.cnt": 707,
    "make-root-fs.ms.sum": 2627,
    "move-to-cg-after-fork.ms.avg": 3,
    "move-to-cg-after-fork.ms.cnt": 706,
    "move-to-cg-after-fork.ms.sum": 2820
  },
  "conf": {
    "startup_pkgs": null,
    "docker_runtime": "",
    "Pkgs_dir": "/tmp/test-cluster/lambda/packages",
    "pip_mirror": "",
    "server_mode": "sock",
    "registry_cache_ms": 5000,
    "handler_cache_mb": 239,
    "max_runners": 0,
    "worker_port": "5000",
    "worker_dir": "/tmp/test-cluster/worker",
    "import_cache_mb": 239,
    "sandbox_config": {
      "processes": 10
    },
    "sandbox": "sock",
    "Skip_pull_existing": false,
    "cluster_name": "/tmp/test-cluster",
    "benchmark_log": "",
    "registry": "/tmp/test-cluster/registry",
    "timing": false,
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "SOCK_base_path": "/tmp/test-cluster/lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 15:23:54 remove /sys/fs/cgroup/devices/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:23:54 remove /sys/fs/cgroup/freezer/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:23:54 remove /sys/fs/cgroup/hugetlb/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:23:54 remove /sys/fs/cgroup/memory/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:23:54 remove /sys/fs/cgroup/perf_event/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:23:54 remove /sys/fs/cgroup/systemd/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:23:54 remove /sys/fs/cgroup/pids/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:23:54 remove worker.pid",
    "2019/06/28 15:23:54 exiting",
    ""
  ]
}
POP conf: {'server_mode': 'sock'}
PUSH conf: {'startup_pkgs': ['parso', 'jedi', 'urllib3', 'idna', 'chardet', 'certifi', 'requests', 'simplejson'], 'registry': '/root/open-lambda/test-registry'}
========================================
ping_test
========================================
RUN ./ol worker -p=/tmp/test-cluster --detach
b'using existing OL directory at /tmp/test-cluster\nStarting worker: pid=13043, port=5000, log=/tmp/test-cluster/worker.out\nready\n'
RUN ./ol kill -p=/tmp/test-cluster
b'Kill worker process with PID 13043\n'
{
  "test": "ping_test",
  "params": {},
  "pass": true,
  "seconds": 2.151122808456421,
  "total_seconds": 21.05898880958557,
  "stats": {
    "pings_per_sec": 464.87819389402256
  },
  "ol-stats": {
    "Create().ms.avg": 149,
    "Create().ms.cnt": 1,
    "Create().ms.sum": 149,
    "acquire-cgroup.ms.avg": 3,
    "acquire-cgroup.ms.cnt": 1,
    "acquire-cgroup.ms.sum": 3,
    "acquire-mem.ms.avg": 3,
    "acquire-mem.ms.cnt": 1,
    "acquire-mem.ms.sum": 3,
    "fresh-proc.ms.avg": 137,
    "fresh-proc.ms.cnt": 1,
    "fresh-proc.ms.sum": 137,
    "make-proc.ms.avg": 137,
    "make-proc.ms.cnt": 1,
    "make-proc.ms.sum": 137,
    "make-root-fs.ms.avg": 4,
    "make-root-fs.ms.cnt": 1,
    "make-root-fs.ms.sum": 4
  },
  "conf": {
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "docker_runtime": "",
    "sandbox": "sock",
    "pip_mirror": "",
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "handler_cache_mb": 239,
    "max_runners": 0,
    "worker_port": "5000",
    "worker_dir": "/tmp/test-cluster/worker",
    "timing": false,
    "import_cache_mb": 239,
    "Pkgs_dir": "/tmp/test-cluster/lambda/packages",
    "Skip_pull_existing": false,
    "cluster_name": "/tmp/test-cluster",
    "benchmark_log": "",
    "registry": "/root/open-lambda/test-registry",
    "sandbox_config": {
      "processes": 10
    },
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "SOCK_base_path": "/tmp/test-cluster/lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 15:24:15 remove /sys/fs/cgroup/devices/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:15 remove /sys/fs/cgroup/freezer/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:15 remove /sys/fs/cgroup/hugetlb/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:15 remove /sys/fs/cgroup/memory/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:15 remove /sys/fs/cgroup/perf_event/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:15 remove /sys/fs/cgroup/systemd/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:15 remove /sys/fs/cgroup/pids/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:15 remove worker.pid",
    "2019/06/28 15:24:15 exiting",
    ""
  ]
}
PUSH conf: {'import_cache_mb': 0, 'handler_cache_mb': 100}
========================================
smoke_tests
========================================
RUN ./ol worker -p=/tmp/test-cluster --detach
b'using existing OL directory at /tmp/test-cluster\nStarting worker: pid=13115, port=5000, log=/tmp/test-cluster/worker.out\nready\n'
RUN ./ol kill -p=/tmp/test-cluster
b'Kill worker process with PID 13115\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 1.6527199745178223,
  "total_seconds": 9.816574573516846,
  "stats": null,
  "ol-stats": {
    "Channel().ms.avg": 0,
    "Channel().ms.cnt": 4,
    "Channel().ms.sum": 0,
    "Create().ms.avg": 141,
    "Create().ms.cnt": 4,
    "Create().ms.sum": 566,
    "Destroy().ms.avg": 6,
    "Destroy().ms.cnt": 3,
    "Destroy().ms.sum": 20,
    "Pause().ms.avg": 4,
    "Pause().ms.cnt": 3,
    "Pause().ms.sum": 13,
    "acquire-cgroup.ms.avg": 0,
    "acquire-cgroup.ms.cnt": 4,
    "acquire-cgroup.ms.sum": 0,
    "acquire-mem.ms.avg": 0,
    "acquire-mem.ms.cnt": 4,
    "acquire-mem.ms.sum": 0,
    "evict.ms.avg": 6,
    "evict.ms.cnt": 3,
    "evict.ms.sum": 20,
    "fresh-proc.ms.avg": 137,
    "fresh-proc.ms.cnt": 4,
    "fresh-proc.ms.sum": 549,
    "make-proc.ms.avg": 137,
    "make-proc.ms.cnt": 4,
    "make-proc.ms.sum": 549,
    "make-root-fs.ms.avg": 4,
    "make-root-fs.ms.cnt": 4,
    "make-root-fs.ms.sum": 16
  },
  "conf": {
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "docker_runtime": "",
    "sandbox": "sock",
    "pip_mirror": "",
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "handler_cache_mb": 100,
    "max_runners": 0,
    "worker_port": "5000",
    "worker_dir": "/tmp/test-cluster/worker",
    "timing": false,
    "import_cache_mb": 0,
    "Pkgs_dir": "/tmp/test-cluster/lambda/packages",
    "Skip_pull_existing": false,
    "cluster_name": "/tmp/test-cluster",
    "benchmark_log": "",
    "registry": "/root/open-lambda/test-registry",
    "sandbox_config": {
      "processes": 10
    },
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "SOCK_base_path": "/tmp/test-cluster/lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 15:24:25 remove /sys/fs/cgroup/devices/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:24:25 remove /sys/fs/cgroup/freezer/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:24:25 remove /sys/fs/cgroup/hugetlb/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:24:25 remove /sys/fs/cgroup/memory/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:24:25 remove /sys/fs/cgroup/perf_event/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:24:25 remove /sys/fs/cgroup/systemd/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:24:25 remove /sys/fs/cgroup/pids/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:24:25 remove worker.pid",
    "2019/06/28 15:24:25 exiting",
    ""
  ]
}
POP conf: {'import_cache_mb': 0, 'handler_cache_mb': 100}
PUSH conf: {'import_cache_mb': 0, 'handler_cache_mb': 250}
========================================
smoke_tests
========================================
RUN ./ol worker -p=/tmp/test-cluster --detach
b'using existing OL directory at /tmp/test-cluster\nStarting worker: pid=13192, port=5000, log=/tmp/test-cluster/worker.out\nready\n'
RUN ./ol kill -p=/tmp/test-cluster
b'Kill worker process with PID 13192\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 1.373077630996704,
  "total_seconds": 9.450424194335938,
  "stats": null,
  "ol-stats": {
    "Channel().ms.avg": 0,
    "Channel().ms.cnt": 4,
    "Channel().ms.sum": 0,
    "Create().ms.avg": 138,
    "Create().ms.cnt": 4,
    "Create().ms.sum": 554,
    "Pause().ms.avg": 5,
    "Pause().ms.cnt": 3,
    "Pause().ms.sum": 16,
    "acquire-cgroup.ms.avg": 0,
    "acquire-cgroup.ms.cnt": 4,
    "acquire-cgroup.ms.sum": 0,
    "acquire-mem.ms.avg": 0,
    "acquire-mem.ms.cnt": 4,
    "acquire-mem.ms.sum": 0,
    "fresh-proc.ms.avg": 134,
    "fresh-proc.ms.cnt": 4,
    "fresh-proc.ms.sum": 539,
    "make-proc.ms.avg": 134,
    "make-proc.ms.cnt": 4,
    "make-proc.ms.sum": 539,
    "make-root-fs.ms.avg": 3,
    "make-root-fs.ms.cnt": 4,
    "make-root-fs.ms.sum": 14
  },
  "conf": {
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "docker_runtime": "",
    "Pkgs_dir": "/tmp/test-cluster/lambda/packages",
    "pip_mirror": "",
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "handler_cache_mb": 250,
    "max_runners": 0,
    "worker_port": "5000",
    "worker_dir": "/tmp/test-cluster/worker",
    "import_cache_mb": 0,
    "sandbox_config": {
      "processes": 10
    },
    "sandbox": "sock",
    "Skip_pull_existing": false,
    "cluster_name": "/tmp/test-cluster",
    "benchmark_log": "",
    "registry": "/root/open-lambda/test-registry",
    "timing": false,
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "SOCK_base_path": "/tmp/test-cluster/lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 15:24:34 remove /sys/fs/cgroup/devices/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:24:34 remove /sys/fs/cgroup/freezer/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:24:34 remove /sys/fs/cgroup/hugetlb/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:24:34 remove /sys/fs/cgroup/memory/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:24:34 remove /sys/fs/cgroup/perf_event/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:24:34 remove /sys/fs/cgroup/systemd/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:24:34 remove /sys/fs/cgroup/pids/sock-handlers [CGROUP POOL sock-handlers]",
    "2019/06/28 15:24:34 remove worker.pid",
    "2019/06/28 15:24:34 exiting",
    ""
  ]
}
POP conf: {'import_cache_mb': 0, 'handler_cache_mb': 250}
PUSH conf: {'import_cache_mb': 250, 'handler_cache_mb': 100}
========================================
smoke_tests
========================================
RUN ./ol worker -p=/tmp/test-cluster --detach
b'using existing OL directory at /tmp/test-cluster\nStarting worker: pid=13270, port=5000, log=/tmp/test-cluster/worker.out\nready\n'
RUN ./ol kill -p=/tmp/test-cluster
b'Kill worker process with PID 13270\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 1.318497657775879,
  "total_seconds": 9.388702154159546,
  "stats": null,
  "ol-stats": {
    "Channel().ms.avg": 0,
    "Channel().ms.cnt": 4,
    "Channel().ms.sum": 0,
    "Create().ms.avg": 142,
    "Create().ms.cnt": 5,
    "Create().ms.sum": 714,
    "Destroy().ms.avg": 4,
    "Destroy().ms.cnt": 3,
    "Destroy().ms.sum": 13,
    "Pause().ms.avg": 6,
    "Pause().ms.cnt": 3,
    "Pause().ms.sum": 18,
    "acquire-cgroup.ms.avg": 0,
    "acquire-cgroup.ms.cnt": 5,
    "acquire-cgroup.ms.sum": 0,
    "acquire-mem.ms.avg": 0,
    "acquire-mem.ms.cnt": 5,
    "acquire-mem.ms.sum": 2,
    "evict.ms.avg": 4,
    "evict.ms.cnt": 3,
    "evict.ms.sum": 13,
    "fresh-proc.ms.avg": 137,
    "fresh-proc.ms.cnt": 5,
    "fresh-proc.ms.sum": 686,
    "make-proc.ms.avg": 137,
    "make-proc.ms.cnt": 5,
    "make-proc.ms.sum": 687,
    "make-root-fs.ms.avg": 4,
    "make-root-fs.ms.cnt": 5,
    "make-root-fs.ms.sum": 21
  },
  "conf": {
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "docker_runtime": "",
    "sandbox": "sock",
    "pip_mirror": "",
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "handler_cache_mb": 100,
    "max_runners": 0,
    "worker_port": "5000",
    "worker_dir": "/tmp/test-cluster/worker",
    "timing": false,
    "import_cache_mb": 250,
    "Pkgs_dir": "/tmp/test-cluster/lambda/packages",
    "Skip_pull_existing": false,
    "cluster_name": "/tmp/test-cluster",
    "benchmark_log": "",
    "registry": "/root/open-lambda/test-registry",
    "sandbox_config": {
      "processes": 10
    },
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "SOCK_base_path": "/tmp/test-cluster/lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 15:24:44 remove /sys/fs/cgroup/devices/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:44 remove /sys/fs/cgroup/freezer/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:44 remove /sys/fs/cgroup/hugetlb/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:44 remove /sys/fs/cgroup/memory/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:44 remove /sys/fs/cgroup/perf_event/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:44 remove /sys/fs/cgroup/systemd/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:44 remove /sys/fs/cgroup/pids/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:44 remove worker.pid",
    "2019/06/28 15:24:44 exiting",
    ""
  ]
}
POP conf: {'import_cache_mb': 250, 'handler_cache_mb': 100}
PUSH conf: {'import_cache_mb': 250, 'handler_cache_mb': 250}
========================================
smoke_tests
========================================
RUN ./ol worker -p=/tmp/test-cluster --detach
b'using existing OL directory at /tmp/test-cluster\nStarting worker: pid=13354, port=5000, log=/tmp/test-cluster/worker.out\nready\n'
RUN ./ol kill -p=/tmp/test-cluster
b'Kill worker process with PID 13354\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 1.4108927249908447,
  "total_seconds": 9.58697247505188,
  "stats": null,
  "ol-stats": {
    "Channel().ms.avg": 0,
    "Channel().ms.cnt": 4,
    "Channel().ms.sum": 0,
    "Create().ms.avg": 142,
    "Create().ms.cnt": 5,
    "Create().ms.sum": 714,
    "Pause().ms.avg": 3,
    "Pause().ms.cnt": 3,
    "Pause().ms.sum": 11,
    "acquire-cgroup.ms.avg": 0,
    "acquire-cgroup.ms.cnt": 5,
    "acquire-cgroup.ms.sum": 3,
    "acquire-mem.ms.avg": 0,
    "acquire-mem.ms.cnt": 5,
    "acquire-mem.ms.sum": 3,
    "fresh-proc.ms.avg": 137,
    "fresh-proc.ms.cnt": 5,
    "fresh-proc.ms.sum": 687,
    "make-proc.ms.avg": 137,
    "make-proc.ms.cnt": 5,
    "make-proc.ms.sum": 687,
    "make-root-fs.ms.avg": 3,
    "make-root-fs.ms.cnt": 5,
    "make-root-fs.ms.sum": 19
  },
  "conf": {
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "docker_runtime": "",
    "Pkgs_dir": "/tmp/test-cluster/lambda/packages",
    "pip_mirror": "",
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "handler_cache_mb": 250,
    "max_runners": 0,
    "worker_port": "5000",
    "worker_dir": "/tmp/test-cluster/worker",
    "import_cache_mb": 250,
    "sandbox_config": {
      "processes": 10
    },
    "sandbox": "sock",
    "Skip_pull_existing": false,
    "cluster_name": "/tmp/test-cluster",
    "benchmark_log": "",
    "registry": "/root/open-lambda/test-registry",
    "timing": false,
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "SOCK_base_path": "/tmp/test-cluster/lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 15:24:53 remove /sys/fs/cgroup/devices/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:53 remove /sys/fs/cgroup/freezer/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:53 remove /sys/fs/cgroup/hugetlb/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:53 remove /sys/fs/cgroup/memory/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:53 remove /sys/fs/cgroup/perf_event/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:53 remove /sys/fs/cgroup/systemd/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:53 remove /sys/fs/cgroup/pids/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:24:53 remove worker.pid",
    "2019/06/28 15:24:53 exiting",
    ""
  ]
}
POP conf: {'import_cache_mb': 250, 'handler_cache_mb': 250}
PUSH conf: {'import_cache_mb': 0, 'handler_cache_mb': 100, 'sandbox': 'docker'}
========================================
smoke_tests
========================================
RUN ./ol worker -p=/tmp/test-cluster --detach
b'using existing OL directory at /tmp/test-cluster\nStarting worker: pid=13438, port=5000, log=/tmp/test-cluster/worker.out\nready\n'
RUN ./ol kill -p=/tmp/test-cluster
b'Kill worker process with PID 13438\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 7.055631875991821,
  "total_seconds": 16.431779623031616,
  "stats": null,
  "ol-stats": {
    "Channel().ms.avg": 0,
    "Channel().ms.cnt": 4,
    "Channel().ms.sum": 0,
    "Create().ms.avg": 1753,
    "Create().ms.cnt": 4,
    "Create().ms.sum": 7012,
    "Pause().ms.avg": 34,
    "Pause().ms.cnt": 3,
    "Pause().ms.sum": 103
  },
  "conf": {
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "docker_runtime": "",
    "sandbox": "docker",
    "pip_mirror": "",
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "handler_cache_mb": 100,
    "max_runners": 0,
    "worker_port": "5000",
    "worker_dir": "/tmp/test-cluster/worker",
    "timing": false,
    "import_cache_mb": 0,
    "Pkgs_dir": "/tmp/test-cluster/lambda/packages",
    "Skip_pull_existing": false,
    "cluster_name": "/tmp/test-cluster",
    "benchmark_log": "",
    "registry": "/root/open-lambda/test-registry",
    "sandbox_config": {
      "processes": 10
    },
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "SOCK_base_path": "/tmp/test-cluster/lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 15:25:09 Pause() [SB 3]",
    "2019/06/28 15:25:09 Receive request to /stats",
    "2019/06/28 15:25:09 received kill signal, cleaning up",
    "2019/06/28 15:25:09 Destroy() [SB 0]",
    "2019/06/28 15:25:09 Destroy() [SB 1]",
    "2019/06/28 15:25:09 Destroy() [SB 2]",
    "2019/06/28 15:25:10 Destroy() [SB 3]",
    "2019/06/28 15:25:10 remove worker.pid",
    "2019/06/28 15:25:10 exiting",
    ""
  ]
}
POP conf: {'import_cache_mb': 0, 'handler_cache_mb': 100, 'sandbox': 'docker'}
PUSH conf: {'import_cache_mb': 0, 'handler_cache_mb': 250, 'sandbox': 'docker'}
========================================
smoke_tests
========================================
RUN ./ol worker -p=/tmp/test-cluster --detach
b'using existing OL directory at /tmp/test-cluster\nStarting worker: pid=14484, port=5000, log=/tmp/test-cluster/worker.out\nready\n'
RUN ./ol kill -p=/tmp/test-cluster
b'Kill worker process with PID 14484\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 3.0521628856658936,
  "total_seconds": 12.017759799957275,
  "stats": null,
  "ol-stats": {
    "Channel().ms.avg": 0,
    "Channel().ms.cnt": 4,
    "Channel().ms.sum": 0,
    "Create().ms.avg": 752,
    "Create().ms.cnt": 4,
    "Create().ms.sum": 3011,
    "Pause().ms.avg": 29,
    "Pause().ms.cnt": 3,
    "Pause().ms.sum": 88
  },
  "conf": {
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "docker_runtime": "",
    "Pkgs_dir": "/tmp/test-cluster/lambda/packages",
    "pip_mirror": "",
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "handler_cache_mb": 250,
    "max_runners": 0,
    "worker_port": "5000",
    "worker_dir": "/tmp/test-cluster/worker",
    "import_cache_mb": 0,
    "sandbox_config": {
      "processes": 10
    },
    "sandbox": "docker",
    "Skip_pull_existing": false,
    "cluster_name": "/tmp/test-cluster",
    "benchmark_log": "",
    "registry": "/root/open-lambda/test-registry",
    "timing": false,
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "SOCK_base_path": "/tmp/test-cluster/lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 15:25:21 Pause() [SB 3]",
    "2019/06/28 15:25:21 Receive request to /stats",
    "2019/06/28 15:25:21 received kill signal, cleaning up",
    "2019/06/28 15:25:21 Destroy() [SB 0]",
    "2019/06/28 15:25:21 Destroy() [SB 1]",
    "2019/06/28 15:25:22 Destroy() [SB 2]",
    "2019/06/28 15:25:22 Destroy() [SB 3]",
    "2019/06/28 15:25:22 remove worker.pid",
    "2019/06/28 15:25:22 exiting",
    ""
  ]
}
POP conf: {'import_cache_mb': 0, 'handler_cache_mb': 250, 'sandbox': 'docker'}
========================================
fork_bomb
========================================
RUN ./ol worker -p=/tmp/test-cluster --detach
b'using existing OL directory at /tmp/test-cluster\nStarting worker: pid=15528, port=5000, log=/tmp/test-cluster/worker.out\nready\n'
RUN ./ol kill -p=/tmp/test-cluster
b'Kill worker process with PID 15528\n'
{
  "test": "fork_bomb",
  "params": {},
  "pass": true,
  "seconds": 0.1644115447998047,
  "total_seconds": 8.529744863510132,
  "stats": null,
  "ol-stats": {
    "Channel().ms.avg": 0,
    "Channel().ms.cnt": 1,
    "Channel().ms.sum": 0,
    "Create().ms.avg": 241,
    "Create().ms.cnt": 2,
    "Create().ms.sum": 482,
    "acquire-cgroup.ms.avg": 1,
    "acquire-cgroup.ms.cnt": 2,
    "acquire-cgroup.ms.sum": 3,
    "acquire-mem.ms.avg": 1,
    "acquire-mem.ms.cnt": 2,
    "acquire-mem.ms.sum": 3,
    "fresh-proc.ms.avg": 232,
    "fresh-proc.ms.cnt": 2,
    "fresh-proc.ms.sum": 465,
    "make-proc.ms.avg": 232,
    "make-proc.ms.cnt": 2,
    "make-proc.ms.sum": 465,
    "make-root-fs.ms.avg": 4,
    "make-root-fs.ms.cnt": 2,
    "make-root-fs.ms.sum": 8
  },
  "conf": {
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "docker_runtime": "",
    "sandbox": "sock",
    "pip_mirror": "",
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "handler_cache_mb": 239,
    "max_runners": 0,
    "worker_port": "5000",
    "worker_dir": "/tmp/test-cluster/worker",
    "timing": false,
    "import_cache_mb": 239,
    "Pkgs_dir": "/tmp/test-cluster/lambda/packages",
    "Skip_pull_existing": false,
    "cluster_name": "/tmp/test-cluster",
    "benchmark_log": "",
    "registry": "/root/open-lambda/test-registry",
    "sandbox_config": {
      "processes": 10
    },
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "SOCK_base_path": "/tmp/test-cluster/lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 15:25:30 remove /sys/fs/cgroup/devices/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:30 remove /sys/fs/cgroup/freezer/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:30 remove /sys/fs/cgroup/hugetlb/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:30 remove /sys/fs/cgroup/memory/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:30 remove /sys/fs/cgroup/perf_event/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:30 remove /sys/fs/cgroup/systemd/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:30 remove /sys/fs/cgroup/pids/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:30 remove worker.pid",
    "2019/06/28 15:25:30 exiting",
    ""
  ]
}
========================================
max_mem_alloc
========================================
RUN ./ol worker -p=/tmp/test-cluster --detach
b'using existing OL directory at /tmp/test-cluster\nStarting worker: pid=15604, port=5000, log=/tmp/test-cluster/worker.out\nready\n'
RUN ./ol kill -p=/tmp/test-cluster
b'Kill worker process with PID 15604\n'
{
  "test": "max_mem_alloc",
  "params": {},
  "pass": true,
  "seconds": 0.9622151851654053,
  "total_seconds": 9.22896146774292,
  "stats": null,
  "ol-stats": {
    "Channel().ms.avg": 0,
    "Channel().ms.cnt": 1,
    "Channel().ms.sum": 0,
    "Create().ms.avg": 144,
    "Create().ms.cnt": 2,
    "Create().ms.sum": 289,
    "Pause().ms.avg": 2,
    "Pause().ms.cnt": 1,
    "Pause().ms.sum": 2,
    "acquire-cgroup.ms.avg": 1,
    "acquire-cgroup.ms.cnt": 2,
    "acquire-cgroup.ms.sum": 3,
    "acquire-mem.ms.avg": 1,
    "acquire-mem.ms.cnt": 2,
    "acquire-mem.ms.sum": 3,
    "fresh-proc.ms.avg": 136,
    "fresh-proc.ms.cnt": 2,
    "fresh-proc.ms.sum": 272,
    "make-proc.ms.avg": 136,
    "make-proc.ms.cnt": 2,
    "make-proc.ms.sum": 272,
    "make-root-fs.ms.avg": 4,
    "make-root-fs.ms.cnt": 2,
    "make-root-fs.ms.sum": 8
  },
  "conf": {
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "docker_runtime": "",
    "sandbox": "sock",
    "pip_mirror": "",
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "handler_cache_mb": 239,
    "max_runners": 0,
    "worker_port": "5000",
    "worker_dir": "/tmp/test-cluster/worker",
    "timing": false,
    "import_cache_mb": 239,
    "Pkgs_dir": "/tmp/test-cluster/lambda/packages",
    "Skip_pull_existing": false,
    "cluster_name": "/tmp/test-cluster",
    "benchmark_log": "",
    "registry": "/root/open-lambda/test-registry",
    "sandbox_config": {
      "processes": 10
    },
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "SOCK_base_path": "/tmp/test-cluster/lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 15:25:40 remove /sys/fs/cgroup/devices/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:40 remove /sys/fs/cgroup/freezer/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:40 remove /sys/fs/cgroup/hugetlb/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:40 remove /sys/fs/cgroup/memory/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:40 remove /sys/fs/cgroup/perf_event/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:40 remove /sys/fs/cgroup/systemd/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:40 remove /sys/fs/cgroup/pids/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:40 remove worker.pid",
    "2019/06/28 15:25:40 exiting",
    ""
  ]
}
POP conf: {'startup_pkgs': ['parso', 'jedi', 'urllib3', 'idna', 'chardet', 'certifi', 'requests', 'simplejson'], 'registry': '/root/open-lambda/test-registry'}
PUSH conf: {'registry': '/tmp/tmpteyyd_vj', 'registry_cache_ms': 3000, 'sandbox': 'sock'}
========================================
update_code
========================================
RUN ./ol worker -p=/tmp/test-cluster --detach
b'using existing OL directory at /tmp/test-cluster\nStarting worker: pid=15721, port=5000, log=/tmp/test-cluster/worker.out\nready\n'
RUN ./ol kill -p=/tmp/test-cluster
b'Kill worker process with PID 15721\n'
{
  "test": "update_code",
  "params": {},
  "pass": true,
  "seconds": 6.156951665878296,
  "total_seconds": 6.492125511169434,
  "stats": null,
  "ol-stats": {
    "Channel().ms.avg": 0,
    "Channel().ms.cnt": 3,
    "Channel().ms.sum": 0,
    "Create().ms.avg": 145,
    "Create().ms.cnt": 4,
    "Create().ms.sum": 582,
    "Destroy().ms.avg": 1,
    "Destroy().ms.cnt": 2,
    "Destroy().ms.sum": 2,
    "Pause().ms.avg": 0,
    "Pause().ms.cnt": 3393,
    "Pause().ms.sum": 15,
    "acquire-cgroup.ms.avg": 0,
    "acquire-cgroup.ms.cnt": 4,
    "acquire-cgroup.ms.sum": 3,
    "acquire-mem.ms.avg": 0,
    "acquire-mem.ms.cnt": 4,
    "acquire-mem.ms.sum": 3,
    "fresh-proc.ms.avg": 139,
    "fresh-proc.ms.cnt": 4,
    "fresh-proc.ms.sum": 559,
    "make-proc.ms.avg": 139,
    "make-proc.ms.cnt": 4,
    "make-proc.ms.sum": 559,
    "make-root-fs.ms.avg": 3,
    "make-root-fs.ms.cnt": 4,
    "make-root-fs.ms.sum": 14
  },
  "conf": {
    "startup_pkgs": null,
    "docker_runtime": "",
    "Pkgs_dir": "/tmp/test-cluster/lambda/packages",
    "pip_mirror": "",
    "server_mode": "lambda",
    "registry_cache_ms": 3000,
    "handler_cache_mb": 239,
    "max_runners": 0,
    "worker_port": "5000",
    "worker_dir": "/tmp/test-cluster/worker",
    "import_cache_mb": 239,
    "sandbox_config": {
      "processes": 10
    },
    "sandbox": "sock",
    "Skip_pull_existing": false,
    "cluster_name": "/tmp/test-cluster",
    "benchmark_log": "",
    "registry": "/tmp/tmpteyyd_vj",
    "timing": false,
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "SOCK_base_path": "/tmp/test-cluster/lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 15:25:46 remove /sys/fs/cgroup/devices/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:46 remove /sys/fs/cgroup/freezer/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:46 remove /sys/fs/cgroup/hugetlb/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:46 remove /sys/fs/cgroup/memory/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:46 remove /sys/fs/cgroup/perf_event/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:46 remove /sys/fs/cgroup/systemd/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:46 remove /sys/fs/cgroup/pids/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:25:46 remove worker.pid",
    "2019/06/28 15:25:46 exiting",
    ""
  ]
}
POP conf: {'registry': '/tmp/tmpteyyd_vj', 'registry_cache_ms': 3000, 'sandbox': 'sock'}
PUSH conf: {'registry': '/root/open-lambda/test-registry', 'import_cache_mb': 250, 'handler_cache_mb': 250, 'sandbox': 'sock'}
========================================
stress_one_lambda
========================================
RUN ./ol worker -p=/tmp/test-cluster --detach
b'using existing OL directory at /tmp/test-cluster\nStarting worker: pid=15766, port=5000, log=/tmp/test-cluster/worker.out\nready\n'
RUN ./ol kill -p=/tmp/test-cluster
b'Kill worker process with PID 15766\n'
{
  "test": "stress_one_lambda",
  "params": {
    "seconds": 15,
    "procs": 1
  },
  "pass": true,
  "seconds": 15.046570062637329,
  "total_seconds": 15.381725549697876,
  "stats": {
    "reqs_per_sec": 290.4
  },
  "ol-stats": {
    "Channel().ms.avg": 0,
    "Channel().ms.cnt": 3,
    "Channel().ms.sum": 0,
    "Create().ms.avg": 144,
    "Create().ms.cnt": 4,
    "Create().ms.sum": 579,
    "Destroy().ms.avg": 1,
    "Destroy().ms.cnt": 2,
    "Destroy().ms.sum": 3,
    "Pause().ms.avg": 0,
    "Pause().ms.cnt": 8709,
    "Pause().ms.sum": 16,
    "acquire-cgroup.ms.avg": 0,
    "acquire-cgroup.ms.cnt": 4,
    "acquire-cgroup.ms.sum": 3,
    "acquire-mem.ms.avg": 0,
    "acquire-mem.ms.cnt": 4,
    "acquire-mem.ms.sum": 0,
    "fresh-proc.ms.avg": 139,
    "fresh-proc.ms.cnt": 4,
    "fresh-proc.ms.sum": 559,
    "make-proc.ms.avg": 139,
    "make-proc.ms.cnt": 4,
    "make-proc.ms.sum": 559,
    "make-root-fs.ms.avg": 3,
    "make-root-fs.ms.cnt": 4,
    "make-root-fs.ms.sum": 14
  },
  "conf": {
    "startup_pkgs": null,
    "docker_runtime": "",
    "sandbox": "sock",
    "pip_mirror": "",
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "handler_cache_mb": 250,
    "max_runners": 0,
    "worker_port": "5000",
    "worker_dir": "/tmp/test-cluster/worker",
    "timing": false,
    "import_cache_mb": 250,
    "Pkgs_dir": "/tmp/test-cluster/lambda/packages",
    "Skip_pull_existing": false,
    "cluster_name": "/tmp/test-cluster",
    "benchmark_log": "",
    "registry": "/root/open-lambda/test-registry",
    "sandbox_config": {
      "processes": 10
    },
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "SOCK_base_path": "/tmp/test-cluster/lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 15:26:02 remove /sys/fs/cgroup/devices/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:02 remove /sys/fs/cgroup/freezer/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:02 remove /sys/fs/cgroup/hugetlb/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:02 remove /sys/fs/cgroup/memory/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:02 remove /sys/fs/cgroup/perf_event/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:02 remove /sys/fs/cgroup/systemd/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:02 remove /sys/fs/cgroup/pids/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:02 remove worker.pid",
    "2019/06/28 15:26:02 exiting",
    ""
  ]
}
========================================
stress_one_lambda
========================================
RUN ./ol worker -p=/tmp/test-cluster --detach
b'using existing OL directory at /tmp/test-cluster\nStarting worker: pid=15814, port=5000, log=/tmp/test-cluster/worker.out\nready\n'
RUN ./ol kill -p=/tmp/test-cluster
b'Kill worker process with PID 15814\n'
{
  "test": "stress_one_lambda",
  "params": {
    "seconds": 15,
    "procs": 2
  },
  "pass": true,
  "seconds": 15.04354739189148,
  "total_seconds": 15.377405643463135,
  "stats": {
    "reqs_per_sec": 292.8
  },
  "ol-stats": {
    "Channel().ms.avg": 0,
    "Channel().ms.cnt": 3,
    "Channel().ms.sum": 0,
    "Create().ms.avg": 145,
    "Create().ms.cnt": 4,
    "Create().ms.sum": 582,
    "Destroy().ms.avg": 1,
    "Destroy().ms.cnt": 2,
    "Destroy().ms.sum": 2,
    "Pause().ms.avg": 0,
    "Pause().ms.cnt": 7943,
    "Pause().ms.sum": 6,
    "acquire-cgroup.ms.avg": 0,
    "acquire-cgroup.ms.cnt": 4,
    "acquire-cgroup.ms.sum": 3,
    "acquire-mem.ms.avg": 0,
    "acquire-mem.ms.cnt": 4,
    "acquire-mem.ms.sum": 3,
    "fresh-proc.ms.avg": 140,
    "fresh-proc.ms.cnt": 4,
    "fresh-proc.ms.sum": 560,
    "make-proc.ms.avg": 140,
    "make-proc.ms.cnt": 4,
    "make-proc.ms.sum": 560,
    "make-root-fs.ms.avg": 3,
    "make-root-fs.ms.cnt": 4,
    "make-root-fs.ms.sum": 14
  },
  "conf": {
    "startup_pkgs": null,
    "docker_runtime": "",
    "sandbox": "sock",
    "pip_mirror": "",
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "handler_cache_mb": 250,
    "max_runners": 0,
    "worker_port": "5000",
    "worker_dir": "/tmp/test-cluster/worker",
    "timing": false,
    "import_cache_mb": 250,
    "Pkgs_dir": "/tmp/test-cluster/lambda/packages",
    "Skip_pull_existing": false,
    "cluster_name": "/tmp/test-cluster",
    "benchmark_log": "",
    "registry": "/root/open-lambda/test-registry",
    "sandbox_config": {
      "processes": 10
    },
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "SOCK_base_path": "/tmp/test-cluster/lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 15:26:17 remove /sys/fs/cgroup/devices/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:17 remove /sys/fs/cgroup/freezer/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:17 remove /sys/fs/cgroup/hugetlb/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:17 remove /sys/fs/cgroup/memory/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:17 remove /sys/fs/cgroup/perf_event/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:17 remove /sys/fs/cgroup/systemd/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:17 remove /sys/fs/cgroup/pids/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:17 remove worker.pid",
    "2019/06/28 15:26:17 exiting",
    ""
  ]
}
PUSH conf: {'import_cache_mb': 100, 'handler_cache_mb': 500}
========================================
stress_one_lambda
========================================
RUN ./ol worker -p=/tmp/test-cluster --detach
b'using existing OL directory at /tmp/test-cluster\nStarting worker: pid=15864, port=5000, log=/tmp/test-cluster/worker.out\nready\n'
RUN ./ol kill -p=/tmp/test-cluster
b'Kill worker process with PID 15864\n'
{
  "test": "stress_one_lambda",
  "params": {
    "seconds": 15,
    "procs": 8
  },
  "pass": true,
  "seconds": 15.066358804702759,
  "total_seconds": 15.4011812210083,
  "stats": {
    "reqs_per_sec": 268.4
  },
  "ol-stats": {
    "Channel().ms.avg": 0,
    "Channel().ms.cnt": 3,
    "Channel().ms.sum": 0,
    "Create().ms.avg": 144,
    "Create().ms.cnt": 4,
    "Create().ms.sum": 578,
    "Destroy().ms.avg": 1,
    "Destroy().ms.cnt": 2,
    "Destroy().ms.sum": 2,
    "Pause().ms.avg": 0,
    "Pause().ms.cnt": 6891,
    "Pause().ms.sum": 725,
    "acquire-cgroup.ms.avg": 0,
    "acquire-cgroup.ms.cnt": 4,
    "acquire-cgroup.ms.sum": 3,
    "acquire-mem.ms.avg": 0,
    "acquire-mem.ms.cnt": 4,
    "acquire-mem.ms.sum": 3,
    "fresh-proc.ms.avg": 138,
    "fresh-proc.ms.cnt": 4,
    "fresh-proc.ms.sum": 554,
    "make-proc.ms.avg": 138,
    "make-proc.ms.cnt": 4,
    "make-proc.ms.sum": 554,
    "make-root-fs.ms.avg": 4,
    "make-root-fs.ms.cnt": 4,
    "make-root-fs.ms.sum": 16
  },
  "conf": {
    "startup_pkgs": null,
    "docker_runtime": "",
    "sandbox": "sock",
    "pip_mirror": "",
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "handler_cache_mb": 500,
    "max_runners": 0,
    "worker_port": "5000",
    "worker_dir": "/tmp/test-cluster/worker",
    "timing": false,
    "import_cache_mb": 100,
    "Pkgs_dir": "/tmp/test-cluster/lambda/packages",
    "Skip_pull_existing": false,
    "cluster_name": "/tmp/test-cluster",
    "benchmark_log": "",
    "registry": "/root/open-lambda/test-registry",
    "sandbox_config": {
      "processes": 10
    },
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "SOCK_base_path": "/tmp/test-cluster/lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 15:26:32 remove /sys/fs/cgroup/devices/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:32 remove /sys/fs/cgroup/freezer/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:32 remove /sys/fs/cgroup/hugetlb/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:32 remove /sys/fs/cgroup/memory/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:32 remove /sys/fs/cgroup/perf_event/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:32 remove /sys/fs/cgroup/systemd/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:32 remove /sys/fs/cgroup/pids/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:32 remove worker.pid",
    "2019/06/28 15:26:32 exiting",
    ""
  ]
}
POP conf: {'import_cache_mb': 100, 'handler_cache_mb': 500}
POP conf: {'registry': '/root/open-lambda/test-registry', 'import_cache_mb': 250, 'handler_cache_mb': 250, 'sandbox': 'sock'}
PUSH conf: {'import_cache_mb': 250, 'handler_cache_mb': 250, 'sandbox': 'sock'}
PUSH conf: {'registry': '/tmp/tmpte751ywo'}
========================================
call_each_once_exec
========================================
RUN ./ol worker -p=/tmp/test-cluster --detach
b'using existing OL directory at /tmp/test-cluster\nStarting worker: pid=15920, port=5000, log=/tmp/test-cluster/worker.out\nready\n'
RUN ./ol kill -p=/tmp/test-cluster
b'Kill worker process with PID 15920\n'
{
  "test": "call_each_once_exec",
  "params": {
    "alloc_mb": 1,
    "lambda_count": 100
  },
  "pass": true,
  "seconds": 15.718236923217773,
  "total_seconds": 16.05902934074402,
  "stats": {
    "reqs_per_sec": 6.362046822150556
  },
  "ol-stats": {
    "Channel().ms.avg": 0,
    "Channel().ms.cnt": 100,
    "Channel().ms.sum": 0,
    "Create().ms.avg": 140,
    "Create().ms.cnt": 101,
    "Create().ms.sum": 14179,
    "Destroy().ms.avg": 6,
    "Destroy().ms.cnt": 96,
    "Destroy().ms.sum": 667,
    "Pause().ms.avg": 4,
    "Pause().ms.cnt": 100,
    "Pause().ms.sum": 492,
    "acquire-cgroup.ms.avg": 0,
    "acquire-cgroup.ms.cnt": 101,
    "acquire-cgroup.ms.sum": 3,
    "acquire-mem.ms.avg": 0,
    "acquire-mem.ms.cnt": 101,
    "acquire-mem.ms.sum": 3,
    "evict.ms.avg": 6,
    "evict.ms.cnt": 96,
    "evict.ms.sum": 670,
    "fresh-proc.ms.avg": 137,
    "fresh-proc.ms.cnt": 101,
    "fresh-proc.ms.sum": 13870,
    "make-proc.ms.avg": 137,
    "make-proc.ms.cnt": 101,
    "make-proc.ms.sum": 13870,
    "make-root-fs.ms.avg": 2,
    "make-root-fs.ms.cnt": 101,
    "make-root-fs.ms.sum": 219
  },
  "conf": {
    "startup_pkgs": null,
    "docker_runtime": "",
    "Pkgs_dir": "/tmp/test-cluster/lambda/packages",
    "pip_mirror": "",
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "handler_cache_mb": 250,
    "max_runners": 0,
    "worker_port": "5000",
    "worker_dir": "/tmp/test-cluster/worker",
    "import_cache_mb": 250,
    "sandbox_config": {
      "processes": 10
    },
    "sandbox": "sock",
    "Skip_pull_existing": false,
    "cluster_name": "/tmp/test-cluster",
    "benchmark_log": "",
    "registry": "/tmp/tmpte751ywo",
    "timing": false,
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "SOCK_base_path": "/tmp/test-cluster/lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 15:26:48 remove /sys/fs/cgroup/devices/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:48 remove /sys/fs/cgroup/freezer/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:48 remove /sys/fs/cgroup/hugetlb/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:48 remove /sys/fs/cgroup/memory/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:48 remove /sys/fs/cgroup/perf_event/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:48 remove /sys/fs/cgroup/systemd/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:48 remove /sys/fs/cgroup/pids/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:26:48 remove worker.pid",
    "2019/06/28 15:26:48 exiting",
    ""
  ]
}
POP conf: {'registry': '/tmp/tmpte751ywo'}
PUSH conf: {'registry': '/tmp/tmpszhuz_vi'}
========================================
call_each_once_exec
========================================
RUN ./ol worker -p=/tmp/test-cluster --detach
b'using existing OL directory at /tmp/test-cluster\nStarting worker: pid=16452, port=5000, log=/tmp/test-cluster/worker.out\nready\n'
RUN ./ol kill -p=/tmp/test-cluster
b'Kill worker process with PID 16452\n'
{
  "test": "call_each_once_exec",
  "params": {
    "alloc_mb": 10,
    "lambda_count": 1000
  },
  "pass": true,
  "seconds": 164.44288420677185,
  "total_seconds": 164.76906943321228,
  "stats": {
    "reqs_per_sec": 6.0811435131247125
  },
  "ol-stats": {
    "Channel().ms.avg": 0,
    "Channel().ms.cnt": 1000,
    "Channel().ms.sum": 0,
    "Create().ms.avg": 145,
    "Create().ms.cnt": 1001,
    "Create().ms.sum": 145377,
    "Destroy().ms.avg": 2,
    "Destroy().ms.cnt": 996,
    "Destroy().ms.sum": 2876,
    "Pause().ms.avg": 7,
    "Pause().ms.cnt": 999,
    "Pause().ms.sum": 7534,
    "acquire-cgroup.ms.avg": 0,
    "acquire-cgroup.ms.cnt": 1001,
    "acquire-cgroup.ms.sum": 3,
    "acquire-mem.ms.avg": 0,
    "acquire-mem.ms.cnt": 1001,
    "acquire-mem.ms.sum": 3,
    "evict.ms.avg": 2,
    "evict.ms.cnt": 996,
    "evict.ms.sum": 2892,
    "fresh-proc.ms.avg": 142,
    "fresh-proc.ms.cnt": 1001,
    "fresh-proc.ms.sum": 142298,
    "make-proc.ms.avg": 142,
    "make-proc.ms.cnt": 1001,
    "make-proc.ms.sum": 142303,
    "make-root-fs.ms.avg": 2,
    "make-root-fs.ms.cnt": 1001,
    "make-root-fs.ms.sum": 2201
  },
  "conf": {
    "startup_pkgs": null,
    "docker_runtime": "",
    "sandbox": "sock",
    "pip_mirror": "",
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "handler_cache_mb": 250,
    "max_runners": 0,
    "worker_port": "5000",
    "worker_dir": "/tmp/test-cluster/worker",
    "timing": false,
    "import_cache_mb": 250,
    "Pkgs_dir": "/tmp/test-cluster/lambda/packages",
    "Skip_pull_existing": false,
    "cluster_name": "/tmp/test-cluster",
    "benchmark_log": "",
    "registry": "/tmp/tmpszhuz_vi",
    "sandbox_config": {
      "processes": 10
    },
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "SOCK_base_path": "/tmp/test-cluster/lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 15:29:33 remove /sys/fs/cgroup/devices/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:29:33 remove /sys/fs/cgroup/freezer/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:29:33 remove /sys/fs/cgroup/hugetlb/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:29:33 remove /sys/fs/cgroup/memory/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:29:33 remove /sys/fs/cgroup/perf_event/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:29:33 remove /sys/fs/cgroup/systemd/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:29:33 remove /sys/fs/cgroup/pids/sock-cache [CGROUP POOL sock-cache]",
    "2019/06/28 15:29:33 remove worker.pid",
    "2019/06/28 15:29:33 exiting",
    ""
  ]
}
POP conf: {'registry': '/tmp/tmpszhuz_vi'}
POP conf: {'import_cache_mb': 250, 'handler_cache_mb': 250, 'sandbox': 'sock'}
PASSED: 17, FAILED: 0