python3 -u test.py
RUN ./ol new -p=test-dir
b'Init OL dir at /root/open-lambda/test-dir\nCreate lambda base at /root/open-lambda/test-dir/lambda (may take several minutes)\nWorking Directory: /root/open-lambda/test-dir\n\nWorker Defaults: \n{\n\t"registry": "/root/open-lambda/test-dir/registry",\n\t"registry_cache_ms": 5000,\n\t"sandbox": "sock",\n\t"cluster_name": "/root/open-lambda/test-dir",\n\t"pip_mirror": "",\n\t"Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",\n\t"handler_cache_mb": 239,\n\t"import_cache_mb": 239,\n\t"server_mode": "lambda",\n\t"worker_dir": "/root/open-lambda/test-dir/worker",\n\t"SOCK_base_path": "/root/open-lambda/test-dir/lambda",\n\t"worker_port": "5000",\n\t"Skip_pull_existing": false,\n\t"sandbox_config": {},\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: /root/open-lambda/test-dir/config.json\n\nYou may now start a server using the "ol worker" command\n'
PUSH conf: {'startup_pkgs': ['parso', 'jedi', 'urllib3', 'idna', 'chardet', 'certifi', 'requests', 'simplejson'], 'registry': '/root/open-lambda/test-registry'}
========================================
ping_test
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=7281, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7281\n'
{
  "test": "ping_test",
  "params": {},
  "pass": true,
  "seconds": 2.414639711380005,
  "total_seconds": 25.354626417160034,
  "stats": {
    "pings_per_sec": 414.1438128150348
  },
  "ol-stats": {
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 1,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 1,
    "Create()/fresh-proc:ms.avg": 1764,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 5,
    "Create()/make-root-fs:ms.cnt": 1,
    "Create():ms.avg": 1769,
    "Create():ms.cnt": 1,
    "Destroy()/detach-root:ms.avg": 0,
    "Destroy()/detach-root:ms.cnt": 1,
    "Destroy()/kill-procs:ms.avg": 1,
    "Destroy()/kill-procs:ms.cnt": 1,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 1,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 1,
    "Destroy():ms.avg": 3,
    "Destroy():ms.cnt": 1
  },
  "conf": {
    "sandbox": "sock",
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "handler_cache_mb": 239,
    "pip_mirror": "",
    "server_mode": "lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Skip_pull_existing": false,
    "import_cache_mb": 239,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "worker_port": "5000",
    "docker_runtime": "",
    "sandbox_config": {},
    "registry": "/root/open-lambda/test-registry"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/30 21:45:43 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:45:43 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:45:43 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:45:43 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:45:43 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:45:43 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:45:43 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/30 21:45:43 remove worker.pid",
    "2019/06/30 21:45:43 exiting",
    ""
  ]
}
PUSH conf: {'import_cache_mb': 0, 'handler_cache_mb': 100}
========================================
smoke_tests
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=7354, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7354\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 1.6814706325531006,
  "total_seconds": 9.74369502067566,
  "stats": null,
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 4,
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 4,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 4,
    "Create()/fresh-proc:ms.avg": 137,
    "Create()/fresh-proc:ms.cnt": 4,
    "Create()/make-root-fs:ms.avg": 4,
    "Create()/make-root-fs:ms.cnt": 4,
    "Create():ms.avg": 142,
    "Create():ms.cnt": 4,
    "Destroy()/detach-root:ms.avg": 0,
    "Destroy()/detach-root:ms.cnt": 4,
    "Destroy()/kill-procs:ms.avg": 4,
    "Destroy()/kill-procs:ms.cnt": 4,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 4,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 4,
    "Destroy():ms.avg": 3,
    "Destroy():ms.cnt": 7,
    "Pause():ms.avg": 3,
    "Pause():ms.cnt": 4,
    "evict:ms.avg": 7,
    "evict:ms.cnt": 3
  },
  "conf": {
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "handler_cache_mb": 100,
    "pip_mirror": "",
    "server_mode": "lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Skip_pull_existing": false,
    "import_cache_mb": 0,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "worker_port": "5000",
    "docker_runtime": "",
    "sandbox_config": {},
    "registry": "/root/open-lambda/test-registry"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/30 21:45:53 remove /sys/fs/cgroup/freezer/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:45:53 remove /sys/fs/cgroup/hugetlb/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:45:53 remove /sys/fs/cgroup/memory/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:45:53 remove /sys/fs/cgroup/perf_event/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:45:53 remove /sys/fs/cgroup/systemd/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:45:53 remove /sys/fs/cgroup/pids/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:45:53 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/30 21:45:53 remove worker.pid",
    "2019/06/30 21:45:53 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=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=7432, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7432\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 1.5332496166229248,
  "total_seconds": 9.602259874343872,
  "stats": null,
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 4,
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 4,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 4,
    "Create()/fresh-proc:ms.avg": 139,
    "Create()/fresh-proc:ms.cnt": 4,
    "Create()/make-root-fs:ms.avg": 3,
    "Create()/make-root-fs:ms.cnt": 4,
    "Create():ms.avg": 143,
    "Create():ms.cnt": 4,
    "Destroy()/detach-root:ms.avg": 0,
    "Destroy()/detach-root:ms.cnt": 4,
    "Destroy()/kill-procs:ms.avg": 1,
    "Destroy()/kill-procs:ms.cnt": 4,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 4,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 4,
    "Destroy():ms.avg": 3,
    "Destroy():ms.cnt": 4,
    "Pause():ms.avg": 5,
    "Pause():ms.cnt": 4
  },
  "conf": {
    "docker_runtime": "",
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "registry_cache_ms": 5000,
    "registry": "/root/open-lambda/test-registry",
    "pip_mirror": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Skip_pull_existing": false,
    "import_cache_mb": 0,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox_config": {},
    "handler_cache_mb": 250,
    "worker_port": "5000",
    "server_mode": "lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/30 21:46:02 remove /sys/fs/cgroup/freezer/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:46:02 remove /sys/fs/cgroup/hugetlb/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:46:02 remove /sys/fs/cgroup/memory/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:46:02 remove /sys/fs/cgroup/perf_event/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:46:02 remove /sys/fs/cgroup/systemd/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:46:02 remove /sys/fs/cgroup/pids/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:46:02 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/30 21:46:02 remove worker.pid",
    "2019/06/30 21:46:02 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=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=7511, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7511\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 0.8616969585418701,
  "total_seconds": 8.92348051071167,
  "stats": null,
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 4,
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 5,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 5,
    "Create()/fork-proc:ms.avg": 8,
    "Create()/fork-proc:ms.cnt": 4,
    "Create()/fresh-proc:ms.avg": 139,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 3,
    "Create()/make-root-fs:ms.cnt": 5,
    "Create():ms.avg": 38,
    "Create():ms.cnt": 5,
    "Destroy()/detach-root:ms.avg": 0,
    "Destroy()/detach-root:ms.cnt": 5,
    "Destroy()/kill-procs:ms.avg": 0,
    "Destroy()/kill-procs:ms.cnt": 5,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 5,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 5,
    "Destroy():ms.avg": 0,
    "Destroy():ms.cnt": 8,
    "Pause():ms.avg": 7,
    "Pause():ms.cnt": 4,
    "evict:ms.avg": 0,
    "evict:ms.cnt": 3,
    "fork():ms.avg": 8,
    "fork():ms.cnt": 4,
    "forkRequest:ms.avg": 7,
    "forkRequest:ms.cnt": 4,
    "move-to-cg-after-fork:ms.avg": 0,
    "move-to-cg-after-fork:ms.cnt": 4
  },
  "conf": {
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "handler_cache_mb": 100,
    "pip_mirror": "",
    "server_mode": "lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Skip_pull_existing": false,
    "import_cache_mb": 250,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "worker_port": "5000",
    "docker_runtime": "",
    "sandbox_config": {},
    "registry": "/root/open-lambda/test-registry"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/30 21:46:11 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:46:11 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:46:11 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:46:11 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:46:11 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:46:11 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:46:11 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/30 21:46:11 remove worker.pid",
    "2019/06/30 21:46:11 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=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=7594, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7594\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 0.9306764602661133,
  "total_seconds": 8.907696008682251,
  "stats": null,
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 4,
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 5,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 5,
    "Create()/fork-proc:ms.avg": 7,
    "Create()/fork-proc:ms.cnt": 4,
    "Create()/fresh-proc:ms.avg": 140,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 4,
    "Create()/make-root-fs:ms.cnt": 5,
    "Create():ms.avg": 38,
    "Create():ms.cnt": 5,
    "Destroy()/detach-root:ms.avg": 0,
    "Destroy()/detach-root:ms.cnt": 5,
    "Destroy()/kill-procs:ms.avg": 1,
    "Destroy()/kill-procs:ms.cnt": 5,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 5,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 5,
    "Destroy():ms.avg": 2,
    "Destroy():ms.cnt": 5,
    "Pause():ms.avg": 9,
    "Pause():ms.cnt": 4,
    "fork():ms.avg": 7,
    "fork():ms.cnt": 4,
    "forkRequest:ms.avg": 5,
    "forkRequest:ms.cnt": 4,
    "move-to-cg-after-fork:ms.avg": 0,
    "move-to-cg-after-fork:ms.cnt": 4
  },
  "conf": {
    "docker_runtime": "",
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "registry_cache_ms": 5000,
    "registry": "/root/open-lambda/test-registry",
    "pip_mirror": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Skip_pull_existing": false,
    "import_cache_mb": 250,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox_config": {},
    "handler_cache_mb": 250,
    "worker_port": "5000",
    "server_mode": "lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/30 21:46:20 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:46:20 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:46:20 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:46:20 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:46:20 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:46:20 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:46:20 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/30 21:46:20 remove worker.pid",
    "2019/06/30 21:46:20 exiting",
    ""
  ]
}
POP conf: {'import_cache_mb': 250, 'handler_cache_mb': 250}
PUSH conf: {'sandbox': 'docker', 'import_cache_mb': 0, 'handler_cache_mb': 100}
========================================
smoke_tests
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=7676, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7676\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 6.052859783172607,
  "total_seconds": 15.314867496490479,
  "stats": null,
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 8,
    "Create():ms.avg": 1503,
    "Create():ms.cnt": 4,
    "Destroy():ms.avg": 323,
    "Destroy():ms.cnt": 8,
    "Pause():ms.avg": 30,
    "Pause():ms.cnt": 8
  },
  "conf": {
    "sandbox": "docker",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "handler_cache_mb": 100,
    "pip_mirror": "",
    "server_mode": "lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Skip_pull_existing": false,
    "import_cache_mb": 0,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "worker_port": "5000",
    "docker_runtime": "",
    "sandbox_config": {},
    "registry": "/root/open-lambda/test-registry"
  },
  "errors": [],
  "worker_tail": [
    "Kill function: install2019/06/30 21:46:35 Destroy() [SB 1]",
    "2019/06/30 21:46:35 Destroy() [SB 1]",
    "Kill function: install22019/06/30 21:46:35 Destroy() [SB 2]",
    "2019/06/30 21:46:35 Destroy() [SB 2]",
    "Kill function: install32019/06/30 21:46:35 Destroy() [SB 3]",
    "2019/06/30 21:46:35 Destroy() [SB 3]",
    "2019/06/30 21:46:36 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/30 21:46:36 remove worker.pid",
    "2019/06/30 21:46:36 exiting",
    ""
  ]
}
POP conf: {'sandbox': 'docker', 'import_cache_mb': 0, 'handler_cache_mb': 100}
PUSH conf: {'sandbox': 'docker', 'import_cache_mb': 0, 'handler_cache_mb': 250}
========================================
smoke_tests
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=8717, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 8717\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 2.7158310413360596,
  "total_seconds": 11.474295616149902,
  "stats": null,
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 8,
    "Create():ms.avg": 668,
    "Create():ms.cnt": 4,
    "Destroy():ms.avg": 208,
    "Destroy():ms.cnt": 8,
    "Pause():ms.avg": 26,
    "Pause():ms.cnt": 8
  },
  "conf": {
    "docker_runtime": "",
    "sandbox": "docker",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "registry_cache_ms": 5000,
    "registry": "/root/open-lambda/test-registry",
    "pip_mirror": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Skip_pull_existing": false,
    "import_cache_mb": 0,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox_config": {},
    "handler_cache_mb": 250,
    "worker_port": "5000",
    "server_mode": "lambda"
  },
  "errors": [],
  "worker_tail": [
    "Kill function: install2019/06/30 21:46:47 Destroy() [SB 1]",
    "2019/06/30 21:46:47 Destroy() [SB 1]",
    "Kill function: install22019/06/30 21:46:47 Destroy() [SB 2]",
    "2019/06/30 21:46:47 Destroy() [SB 2]",
    "Kill function: install32019/06/30 21:46:47 Destroy() [SB 3]",
    "2019/06/30 21:46:47 Destroy() [SB 3]",
    "2019/06/30 21:46:47 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/30 21:46:47 remove worker.pid",
    "2019/06/30 21:46:47 exiting",
    ""
  ]
}
POP conf: {'sandbox': 'docker', 'import_cache_mb': 0, 'handler_cache_mb': 250}
========================================
fork_bomb
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=9766, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 9766\n'
{
  "test": "fork_bomb",
  "params": {},
  "pass": true,
  "seconds": 0.1481156349182129,
  "total_seconds": 8.101459503173828,
  "stats": null,
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 1,
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 2,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 2,
    "Create()/fork-proc:ms.avg": 7,
    "Create()/fork-proc:ms.cnt": 1,
    "Create()/fresh-proc:ms.avg": 141,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 4,
    "Create()/make-root-fs:ms.cnt": 2,
    "Create():ms.avg": 79,
    "Create():ms.cnt": 2,
    "Destroy()/detach-root:ms.avg": 1,
    "Destroy()/detach-root:ms.cnt": 2,
    "Destroy()/kill-procs:ms.avg": 0,
    "Destroy()/kill-procs:ms.cnt": 2,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 2,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 2,
    "Destroy():ms.avg": 2,
    "Destroy():ms.cnt": 2,
    "Pause():ms.avg": 5,
    "Pause():ms.cnt": 1,
    "fork():ms.avg": 5,
    "fork():ms.cnt": 1,
    "forkRequest:ms.avg": 5,
    "forkRequest:ms.cnt": 1,
    "move-to-cg-after-fork:ms.avg": 0,
    "move-to-cg-after-fork:ms.cnt": 1
  },
  "conf": {
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "handler_cache_mb": 239,
    "pip_mirror": "",
    "server_mode": "lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Skip_pull_existing": false,
    "import_cache_mb": 239,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "worker_port": "5000",
    "docker_runtime": "",
    "sandbox_config": {},
    "registry": "/root/open-lambda/test-registry"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/30 21:46:55 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:46:55 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:46:55 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:46:55 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:46:55 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:46:55 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:46:55 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/30 21:46:55 remove worker.pid",
    "2019/06/30 21:46:55 exiting",
    ""
  ]
}
========================================
max_mem_alloc
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=9840, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 9840\n'
{
  "test": "max_mem_alloc",
  "params": {},
  "pass": true,
  "seconds": 0.929999589920044,
  "total_seconds": 8.893155336380005,
  "stats": null,
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 1,
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 2,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 2,
    "Create()/fork-proc:ms.avg": 8,
    "Create()/fork-proc:ms.cnt": 1,
    "Create()/fresh-proc:ms.avg": 143,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 4,
    "Create()/make-root-fs:ms.cnt": 2,
    "Create():ms.avg": 80,
    "Create():ms.cnt": 2,
    "Destroy()/detach-root:ms.avg": 1,
    "Destroy()/detach-root:ms.cnt": 2,
    "Destroy()/kill-procs:ms.avg": 0,
    "Destroy()/kill-procs:ms.cnt": 2,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 2,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 2,
    "Destroy():ms.avg": 3,
    "Destroy():ms.cnt": 2,
    "Pause():ms.avg": 4,
    "Pause():ms.cnt": 1,
    "fork():ms.avg": 8,
    "fork():ms.cnt": 1,
    "forkRequest:ms.avg": 7,
    "forkRequest:ms.cnt": 1,
    "move-to-cg-after-fork:ms.avg": 0,
    "move-to-cg-after-fork:ms.cnt": 1
  },
  "conf": {
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "handler_cache_mb": 239,
    "pip_mirror": "",
    "server_mode": "lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Skip_pull_existing": false,
    "import_cache_mb": 239,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "worker_port": "5000",
    "docker_runtime": "",
    "sandbox_config": {},
    "registry": "/root/open-lambda/test-registry"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/30 21:47:04 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:47:04 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:47:04 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:47:04 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:47:04 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:47:04 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:47:04 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/30 21:47:04 remove worker.pid",
    "2019/06/30 21:47:04 exiting",
    ""
  ]
}
POP conf: {'startup_pkgs': ['parso', 'jedi', 'urllib3', 'idna', 'chardet', 'certifi', 'requests', 'simplejson'], 'registry': '/root/open-lambda/test-registry'}
PUSH conf: {'server_mode': 'sock'}
========================================
sock_churn
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=9954, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 9954\n'
{
  "test": "sock_churn",
  "params": {
    "baseline": 0,
    "seconds": 15,
    "procs": 1,
    "fork": true
  },
  "pass": true,
  "seconds": 15.219902992248535,
  "total_seconds": 15.44356894493103,
  "stats": {
    "sandboxes_per_sec": 47.8
  },
  "ol-stats": {
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 718,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 718,
    "Create()/fork-proc:ms.avg": 6,
    "Create()/fork-proc:ms.cnt": 717,
    "Create()/fresh-proc:ms.avg": 133,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 2,
    "Create()/make-root-fs:ms.cnt": 718,
    "Create():ms.avg": 9,
    "Create():ms.cnt": 718,
    "Destroy()/detach-root:ms.avg": 1,
    "Destroy()/detach-root:ms.cnt": 718,
    "Destroy()/kill-procs:ms.avg": 1,
    "Destroy()/kill-procs:ms.cnt": 718,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 718,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 718,
    "Destroy():ms.avg": 1,
    "Destroy():ms.cnt": 1435,
    "fork():ms.avg": 6,
    "fork():ms.cnt": 717,
    "forkRequest:ms.avg": 5,
    "forkRequest:ms.cnt": 717,
    "move-to-cg-after-fork:ms.avg": 0,
    "move-to-cg-after-fork:ms.cnt": 717
  },
  "conf": {
    "docker_runtime": "",
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "startup_pkgs": null,
    "registry_cache_ms": 5000,
    "registry": "/root/open-lambda/test-dir/registry",
    "pip_mirror": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Skip_pull_existing": false,
    "import_cache_mb": 239,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox_config": {},
    "handler_cache_mb": 239,
    "worker_port": "5000",
    "server_mode": "sock"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/30 21:47:20 remove /sys/fs/cgroup/freezer/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:47:20 remove /sys/fs/cgroup/hugetlb/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:47:20 remove /sys/fs/cgroup/memory/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:47:20 remove /sys/fs/cgroup/perf_event/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:47:20 remove /sys/fs/cgroup/systemd/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:47:20 remove /sys/fs/cgroup/pids/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:47:20 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/30 21:47:20 remove worker.pid",
    "2019/06/30 21:47:20 exiting",
    ""
  ]
}
========================================
sock_churn
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=12853, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 12853\n'
{
  "test": "sock_churn",
  "params": {
    "baseline": 0,
    "seconds": 15,
    "procs": 15,
    "fork": true
  },
  "pass": true,
  "seconds": 15.358186483383179,
  "total_seconds": 15.598713636398315,
  "stats": {
    "sandboxes_per_sec": 50.0
  },
  "ol-stats": {
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 751,
    "Create()/acquire-mem:ms.avg": 115,
    "Create()/acquire-mem:ms.cnt": 751,
    "Create()/fork-proc:ms.avg": 10,
    "Create()/fork-proc:ms.cnt": 750,
    "Create()/fresh-proc:ms.avg": 134,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 1,
    "Create()/make-root-fs:ms.cnt": 751,
    "Create():ms.avg": 128,
    "Create():ms.cnt": 751,
    "Destroy()/detach-root:ms.avg": 0,
    "Destroy()/detach-root:ms.cnt": 751,
    "Destroy()/kill-procs:ms.avg": 1,
    "Destroy()/kill-procs:ms.cnt": 751,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 751,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 751,
    "Destroy():ms.avg": 19,
    "Destroy():ms.cnt": 1501,
    "fork():ms.avg": 10,
    "fork():ms.cnt": 750,
    "forkRequest:ms.avg": 5,
    "forkRequest:ms.cnt": 750,
    "move-to-cg-after-fork:ms.avg": 1,
    "move-to-cg-after-fork:ms.cnt": 750
  },
  "conf": {
    "docker_runtime": "",
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "startup_pkgs": null,
    "registry_cache_ms": 5000,
    "registry": "/root/open-lambda/test-dir/registry",
    "pip_mirror": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Skip_pull_existing": false,
    "import_cache_mb": 239,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox_config": {},
    "handler_cache_mb": 239,
    "worker_port": "5000",
    "server_mode": "sock"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/30 21:47:35 remove /sys/fs/cgroup/freezer/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:47:35 remove /sys/fs/cgroup/hugetlb/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:47:35 remove /sys/fs/cgroup/memory/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:47:35 remove /sys/fs/cgroup/perf_event/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:47:35 remove /sys/fs/cgroup/systemd/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:47:35 remove /sys/fs/cgroup/pids/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/30 21:47:35 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/30 21:47:35 remove worker.pid",
    "2019/06/30 21:47:35 exiting",
    ""
  ]
}
POP conf: {'server_mode': 'sock'}
PUSH conf: {'sandbox': 'sock', 'registry_cache_ms': 3000, 'registry': '/tmp/tmpy6ao0r29'}
========================================
update_code
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=15901, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 15901\n'
{
  "test": "update_code",
  "params": {},
  "pass": true,
  "seconds": 6.0240819454193115,
  "total_seconds": 6.258771181106567,
  "stats": null,
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 3,
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 4,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 4,
    "Create()/fork-proc:ms.avg": 8,
    "Create()/fork-proc:ms.cnt": 3,
    "Create()/fresh-proc:ms.avg": 143,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 3,
    "Create()/make-root-fs:ms.cnt": 4,
    "Create():ms.avg": 46,
    "Create():ms.cnt": 4,
    "Destroy()/detach-root:ms.avg": 0,
    "Destroy()/detach-root:ms.cnt": 4,
    "Destroy()/kill-procs:ms.avg": 0,
    "Destroy()/kill-procs:ms.cnt": 4,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 4,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 4,
    "Destroy():ms.avg": 1,
    "Destroy():ms.cnt": 4,
    "Pause():ms.avg": 0,
    "Pause():ms.cnt": 1793,
    "Unpause():ms.avg": 0,
    "Unpause():ms.cnt": 1790,
    "fork():ms.avg": 8,
    "fork():ms.cnt": 3,
    "forkRequest:ms.avg": 7,
    "forkRequest:ms.cnt": 3,
    "move-to-cg-after-fork:ms.avg": 0,
    "move-to-cg-after-fork:ms.cnt": 3
  },
  "conf": {
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "registry_cache_ms": 3000,
    "startup_pkgs": null,
    "handler_cache_mb": 239,
    "pip_mirror": "",
    "server_mode": "lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Skip_pull_existing": false,
    "import_cache_mb": 239,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "worker_port": "5000",
    "docker_runtime": "",
    "sandbox_config": {},
    "registry": "/tmp/tmpy6ao0r29"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/30 21:47:41 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:47:41 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:47:41 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:47:41 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:47:41 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:47:41 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:47:41 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/30 21:47:41 remove worker.pid",
    "2019/06/30 21:47:41 exiting",
    ""
  ]
}
POP conf: {'sandbox': 'sock', 'registry_cache_ms': 3000, 'registry': '/tmp/tmpy6ao0r29'}
PUSH conf: {'sandbox': 'sock', 'handler_cache_mb': 250, 'import_cache_mb': 250, 'registry': '/root/open-lambda/test-registry'}
========================================
stress_one_lambda
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=15944, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 15944\n'
{
  "test": "stress_one_lambda",
  "params": {
    "seconds": 15,
    "procs": 1
  },
  "pass": true,
  "seconds": 15.049362421035767,
  "total_seconds": 15.276691675186157,
  "stats": {
    "reqs_per_sec": 297.73333333333335
  },
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 3,
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 4,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 4,
    "Create()/fork-proc:ms.avg": 8,
    "Create()/fork-proc:ms.cnt": 3,
    "Create()/fresh-proc:ms.avg": 147,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 3,
    "Create()/make-root-fs:ms.cnt": 4,
    "Create():ms.avg": 47,
    "Create():ms.cnt": 4,
    "Destroy()/detach-root:ms.avg": 0,
    "Destroy()/detach-root:ms.cnt": 4,
    "Destroy()/kill-procs:ms.avg": 0,
    "Destroy()/kill-procs:ms.cnt": 4,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 4,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 4,
    "Destroy():ms.avg": 1,
    "Destroy():ms.cnt": 4,
    "Pause():ms.avg": 0,
    "Pause():ms.cnt": 4466,
    "Unpause():ms.avg": 0,
    "Unpause():ms.cnt": 4463,
    "fork():ms.avg": 8,
    "fork():ms.cnt": 3,
    "forkRequest:ms.avg": 7,
    "forkRequest:ms.cnt": 3,
    "move-to-cg-after-fork:ms.avg": 1,
    "move-to-cg-after-fork:ms.cnt": 3
  },
  "conf": {
    "docker_runtime": "",
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "startup_pkgs": null,
    "registry_cache_ms": 5000,
    "registry": "/root/open-lambda/test-registry",
    "pip_mirror": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Skip_pull_existing": false,
    "import_cache_mb": 250,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox_config": {},
    "handler_cache_mb": 250,
    "worker_port": "5000",
    "server_mode": "lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/30 21:47:57 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:47:57 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:47:57 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:47:57 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:47:57 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:47:57 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:47:57 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/30 21:47:57 remove worker.pid",
    "2019/06/30 21:47:57 exiting",
    ""
  ]
}
========================================
stress_one_lambda
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=15990, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 15990\n'
{
  "test": "stress_one_lambda",
  "params": {
    "seconds": 15,
    "procs": 2
  },
  "pass": true,
  "seconds": 15.049050569534302,
  "total_seconds": 15.277470111846924,
  "stats": {
    "reqs_per_sec": 297.2
  },
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 3,
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 4,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 4,
    "Create()/fork-proc:ms.avg": 9,
    "Create()/fork-proc:ms.cnt": 3,
    "Create()/fresh-proc:ms.avg": 151,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 3,
    "Create()/make-root-fs:ms.cnt": 4,
    "Create():ms.avg": 48,
    "Create():ms.cnt": 4,
    "Destroy()/detach-root:ms.avg": 0,
    "Destroy()/detach-root:ms.cnt": 4,
    "Destroy()/kill-procs:ms.avg": 0,
    "Destroy()/kill-procs:ms.cnt": 4,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 4,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 4,
    "Destroy():ms.avg": 1,
    "Destroy():ms.cnt": 4,
    "Pause():ms.avg": 0,
    "Pause():ms.cnt": 4075,
    "Unpause():ms.avg": 0,
    "Unpause():ms.cnt": 4072,
    "fork():ms.avg": 9,
    "fork():ms.cnt": 3,
    "forkRequest:ms.avg": 7,
    "forkRequest:ms.cnt": 3,
    "move-to-cg-after-fork:ms.avg": 1,
    "move-to-cg-after-fork:ms.cnt": 3
  },
  "conf": {
    "docker_runtime": "",
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "startup_pkgs": null,
    "registry_cache_ms": 5000,
    "registry": "/root/open-lambda/test-registry",
    "pip_mirror": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Skip_pull_existing": false,
    "import_cache_mb": 250,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox_config": {},
    "handler_cache_mb": 250,
    "worker_port": "5000",
    "server_mode": "lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/30 21:48:12 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:48:12 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:48:12 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:48:12 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:48:12 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:48:12 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:48:12 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/30 21:48:12 remove worker.pid",
    "2019/06/30 21:48:12 exiting",
    ""
  ]
}
PUSH conf: {'import_cache_mb': 100, 'handler_cache_mb': 500}
========================================
stress_one_lambda
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=16038, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 16038\n'
{
  "test": "stress_one_lambda",
  "params": {
    "seconds": 15,
    "procs": 8
  },
  "pass": true,
  "seconds": 15.06980013847351,
  "total_seconds": 15.302361488342285,
  "stats": {
    "reqs_per_sec": 267.8666666666667
  },
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 3,
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 4,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 4,
    "Create()/fork-proc:ms.avg": 10,
    "Create()/fork-proc:ms.cnt": 3,
    "Create()/fresh-proc:ms.avg": 191,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 3,
    "Create()/make-root-fs:ms.cnt": 4,
    "Create():ms.avg": 59,
    "Create():ms.cnt": 4,
    "Destroy()/detach-root:ms.avg": 0,
    "Destroy()/detach-root:ms.cnt": 4,
    "Destroy()/kill-procs:ms.avg": 0,
    "Destroy()/kill-procs:ms.cnt": 4,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 4,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 4,
    "Destroy():ms.avg": 1,
    "Destroy():ms.cnt": 4,
    "Pause():ms.avg": 0,
    "Pause():ms.cnt": 3395,
    "Unpause():ms.avg": 0,
    "Unpause():ms.cnt": 3392,
    "fork():ms.avg": 10,
    "fork():ms.cnt": 3,
    "forkRequest:ms.avg": 6,
    "forkRequest:ms.cnt": 3,
    "move-to-cg-after-fork:ms.avg": 2,
    "move-to-cg-after-fork:ms.cnt": 3
  },
  "conf": {
    "docker_runtime": "",
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "startup_pkgs": null,
    "registry_cache_ms": 5000,
    "registry": "/root/open-lambda/test-registry",
    "pip_mirror": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Skip_pull_existing": false,
    "import_cache_mb": 100,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox_config": {},
    "handler_cache_mb": 500,
    "worker_port": "5000",
    "server_mode": "lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/30 21:48:27 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:48:27 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:48:27 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:48:27 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:48:27 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:48:27 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:48:27 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/30 21:48:27 remove worker.pid",
    "2019/06/30 21:48:27 exiting",
    ""
  ]
}
POP conf: {'import_cache_mb': 100, 'handler_cache_mb': 500}
POP conf: {'sandbox': 'sock', 'handler_cache_mb': 250, 'import_cache_mb': 250, 'registry': '/root/open-lambda/test-registry'}
PUSH conf: {'sandbox': 'sock', 'import_cache_mb': 250, 'handler_cache_mb': 250}
PUSH conf: {'registry': '/tmp/tmpo7td5r_b'}
========================================
call_each_once_exec
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=16093, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 16093\n'
{
  "test": "call_each_once_exec",
  "params": {
    "lambda_count": 100,
    "alloc_mb": 1
  },
  "pass": true,
  "seconds": 2.4702632427215576,
  "total_seconds": 2.709223747253418,
  "stats": {
    "reqs_per_sec": 40.48190631443977
  },
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 100,
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 101,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 101,
    "Create()/fork-proc:ms.avg": 7,
    "Create()/fork-proc:ms.cnt": 100,
    "Create()/fresh-proc:ms.avg": 141,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 0,
    "Create()/make-root-fs:ms.cnt": 101,
    "Create():ms.avg": 9,
    "Create():ms.cnt": 101,
    "Destroy()/detach-root:ms.avg": 0,
    "Destroy()/detach-root:ms.cnt": 101,
    "Destroy()/kill-procs:ms.avg": 0,
    "Destroy()/kill-procs:ms.cnt": 101,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 101,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 101,
    "Destroy():ms.avg": 0,
    "Destroy():ms.cnt": 197,
    "Pause():ms.avg": 3,
    "Pause():ms.cnt": 100,
    "evict:ms.avg": 0,
    "evict:ms.cnt": 96,
    "fork():ms.avg": 7,
    "fork():ms.cnt": 100,
    "forkRequest:ms.avg": 5,
    "forkRequest:ms.cnt": 100,
    "move-to-cg-after-fork:ms.avg": 0,
    "move-to-cg-after-fork:ms.cnt": 100
  },
  "conf": {
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "registry_cache_ms": 5000,
    "startup_pkgs": null,
    "handler_cache_mb": 250,
    "pip_mirror": "",
    "server_mode": "lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Skip_pull_existing": false,
    "import_cache_mb": 250,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "worker_port": "5000",
    "docker_runtime": "",
    "sandbox_config": {},
    "registry": "/tmp/tmpo7td5r_b"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/30 21:48:30 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:48:30 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:48:30 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:48:30 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:48:30 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:48:30 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:48:30 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/30 21:48:30 remove worker.pid",
    "2019/06/30 21:48:30 exiting",
    ""
  ]
}
POP conf: {'registry': '/tmp/tmpo7td5r_b'}
PUSH conf: {'registry': '/tmp/tmp759yfqn6'}
========================================
call_each_once_exec
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=16621, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 16621\n'
{
  "test": "call_each_once_exec",
  "params": {
    "lambda_count": 1000,
    "alloc_mb": 10
  },
  "pass": true,
  "seconds": 35.74420714378357,
  "total_seconds": 36.07903218269348,
  "stats": {
    "reqs_per_sec": 27.976584002386467
  },
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 1000,
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 1001,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 1001,
    "Create()/fork-proc:ms.avg": 7,
    "Create()/fork-proc:ms.cnt": 1000,
    "Create()/fresh-proc:ms.avg": 188,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 2,
    "Create()/make-root-fs:ms.cnt": 1001,
    "Create():ms.avg": 10,
    "Create():ms.cnt": 1001,
    "Destroy()/detach-root:ms.avg": 0,
    "Destroy()/detach-root:ms.cnt": 1001,
    "Destroy()/kill-procs:ms.avg": 2,
    "Destroy()/kill-procs:ms.cnt": 1001,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 1001,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 1001,
    "Destroy():ms.avg": 1,
    "Destroy():ms.cnt": 1997,
    "Pause():ms.avg": 10,
    "Pause():ms.cnt": 1000,
    "evict:ms.avg": 2,
    "evict:ms.cnt": 996,
    "fork():ms.avg": 7,
    "fork():ms.cnt": 1000,
    "forkRequest:ms.avg": 6,
    "forkRequest:ms.cnt": 1000,
    "move-to-cg-after-fork:ms.avg": 0,
    "move-to-cg-after-fork:ms.cnt": 1000
  },
  "conf": {
    "docker_runtime": "",
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "startup_pkgs": null,
    "registry_cache_ms": 5000,
    "registry": "/tmp/tmp759yfqn6",
    "pip_mirror": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Skip_pull_existing": false,
    "import_cache_mb": 250,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox_config": {},
    "handler_cache_mb": 250,
    "worker_port": "5000",
    "server_mode": "lambda"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/30 21:49:06 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:49:06 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:49:06 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:49:06 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:49:06 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:49:06 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/30 21:49:06 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/30 21:49:06 remove worker.pid",
    "2019/06/30 21:49:06 exiting",
    ""
  ]
}
POP conf: {'registry': '/tmp/tmp759yfqn6'}
POP conf: {'sandbox': 'sock', 'import_cache_mb': 250, 'handler_cache_mb': 250}
PASSED: 17, FAILED: 0