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: {'registry': '/root/open-lambda/test-registry', 'startup_pkgs': ['parso', 'jedi', 'urllib3', 'idna', 'chardet', 'certifi', 'requests', 'simplejson']}
========================================
ping_test
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=7297, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7297\n'
{
  "test": "ping_test",
  "params": {},
  "pass": true,
  "seconds": 2.3107237815856934,
  "total_seconds": 23.188692331314087,
  "stats": {
    "pings_per_sec": 432.7684535695496
  },
  "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": 1602,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 9,
    "Create()/make-root-fs:ms.cnt": 1,
    "Create():ms.avg": 1612,
    "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():ms.avg": 1,
    "Destroy():ms.cnt": 1
  },
  "conf": {
    "Skip_pull_existing": false,
    "registry": "/root/open-lambda/test-registry",
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "registry_cache_ms": 5000,
    "server_mode": "lambda",
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "import_cache_mb": 239,
    "handler_cache_mb": 239,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "sandbox_config": {},
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "pip_mirror": "",
    "worker_port": "5000",
    "docker_runtime": ""
  },
  "errors": [],
  "worker_tail": [
    "2019/07/02 21:45:27 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:27 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:27 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:27 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:27 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:27 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:27 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/07/02 21:45:27 remove worker.pid",
    "2019/07/02 21:45:27 exiting",
    ""
  ]
}
PUSH conf: {'handler_cache_mb': 100, 'import_cache_mb': 0}
========================================
smoke_tests
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=7370, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7370\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 2.0490798950195312,
  "total_seconds": 2.2779130935668945,
  "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": 141,
    "Create():ms.cnt": 4,
    "Destroy()/detach-root:ms.avg": 0,
    "Destroy()/detach-root:ms.cnt": 4,
    "Destroy()/kill-procs:ms.avg": 3,
    "Destroy()/kill-procs:ms.cnt": 4,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 4,
    "Destroy():ms.avg": 2,
    "Destroy():ms.cnt": 7,
    "Pause():ms.avg": 3,
    "Pause():ms.cnt": 4,
    "evict:ms.avg": 4,
    "evict:ms.cnt": 3
  },
  "conf": {
    "Skip_pull_existing": false,
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-registry",
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "registry_cache_ms": 5000,
    "server_mode": "lambda",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "import_cache_mb": 0,
    "handler_cache_mb": 100,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "sandbox_config": {},
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "sandbox": "sock",
    "worker_port": "5000",
    "docker_runtime": ""
  },
  "errors": [],
  "worker_tail": [
    "2019/07/02 21:45:29 remove /sys/fs/cgroup/freezer/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:45:29 remove /sys/fs/cgroup/hugetlb/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:45:29 remove /sys/fs/cgroup/memory/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:45:29 remove /sys/fs/cgroup/perf_event/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:45:29 remove /sys/fs/cgroup/systemd/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:45:29 remove /sys/fs/cgroup/pids/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:45:29 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/07/02 21:45:29 remove worker.pid",
    "2019/07/02 21:45:29 exiting",
    ""
  ]
}
POP conf: {'handler_cache_mb': 100, 'import_cache_mb': 0}
PUSH conf: {'handler_cache_mb': 250, 'import_cache_mb': 0}
========================================
smoke_tests
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=7417, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7417\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 1.585885763168335,
  "total_seconds": 1.82185959815979,
  "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": 141,
    "Create()/fresh-proc:ms.cnt": 4,
    "Create()/make-root-fs:ms.avg": 3,
    "Create()/make-root-fs:ms.cnt": 4,
    "Create():ms.avg": 146,
    "Create():ms.cnt": 4,
    "Destroy()/detach-root:ms.avg": 0,
    "Destroy()/detach-root:ms.cnt": 4,
    "Destroy()/kill-procs:ms.avg": 3,
    "Destroy()/kill-procs:ms.cnt": 4,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 4,
    "Destroy():ms.avg": 4,
    "Destroy():ms.cnt": 4,
    "Pause():ms.avg": 7,
    "Pause():ms.cnt": 4
  },
  "conf": {
    "Skip_pull_existing": false,
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-registry",
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "registry_cache_ms": 5000,
    "server_mode": "lambda",
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "import_cache_mb": 0,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "sandbox_config": {},
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "handler_cache_mb": 250,
    "worker_port": "5000",
    "docker_runtime": ""
  },
  "errors": [],
  "worker_tail": [
    "2019/07/02 21:45:31 remove /sys/fs/cgroup/freezer/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:45:31 remove /sys/fs/cgroup/hugetlb/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:45:31 remove /sys/fs/cgroup/memory/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:45:31 remove /sys/fs/cgroup/perf_event/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:45:31 remove /sys/fs/cgroup/systemd/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:45:31 remove /sys/fs/cgroup/pids/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:45:31 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/07/02 21:45:31 remove worker.pid",
    "2019/07/02 21:45:31 exiting",
    ""
  ]
}
POP conf: {'handler_cache_mb': 250, 'import_cache_mb': 0}
PUSH conf: {'handler_cache_mb': 100, 'import_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=7467, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7467\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 0.8734936714172363,
  "total_seconds": 1.107041835784912,
  "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": 142,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 3,
    "Create()/make-root-fs:ms.cnt": 5,
    "Create():ms.avg": 39,
    "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():ms.avg": 1,
    "Destroy():ms.cnt": 8,
    "Pause():ms.avg": 6,
    "Pause():ms.cnt": 4,
    "evict:ms.avg": 1,
    "evict:ms.cnt": 3,
    "fork():ms.avg": 8,
    "fork():ms.cnt": 4,
    "forkRequest:ms.avg": 6,
    "forkRequest:ms.cnt": 4,
    "move-to-cg-after-fork:ms.avg": 1,
    "move-to-cg-after-fork:ms.cnt": 4
  },
  "conf": {
    "Skip_pull_existing": false,
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-registry",
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "registry_cache_ms": 5000,
    "server_mode": "lambda",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "import_cache_mb": 250,
    "handler_cache_mb": 100,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "sandbox_config": {},
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "sandbox": "sock",
    "worker_port": "5000",
    "docker_runtime": ""
  },
  "errors": [],
  "worker_tail": [
    "2019/07/02 21:45:32 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:32 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:32 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:32 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:32 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:32 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:32 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/07/02 21:45:32 remove worker.pid",
    "2019/07/02 21:45:32 exiting",
    ""
  ]
}
POP conf: {'handler_cache_mb': 100, 'import_cache_mb': 250}
PUSH conf: {'handler_cache_mb': 250, 'import_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=7521, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7521\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 0.8614416122436523,
  "total_seconds": 1.1062378883361816,
  "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": 144,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 3,
    "Create()/make-root-fs:ms.cnt": 5,
    "Create():ms.avg": 39,
    "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():ms.avg": 2,
    "Destroy():ms.cnt": 5,
    "Pause():ms.avg": 3,
    "Pause():ms.cnt": 4,
    "fork():ms.avg": 8,
    "fork():ms.cnt": 4,
    "forkRequest:ms.avg": 5,
    "forkRequest:ms.cnt": 4,
    "move-to-cg-after-fork:ms.avg": 1,
    "move-to-cg-after-fork:ms.cnt": 4
  },
  "conf": {
    "Skip_pull_existing": false,
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-registry",
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "registry_cache_ms": 5000,
    "server_mode": "lambda",
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "import_cache_mb": 250,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "sandbox_config": {},
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "handler_cache_mb": 250,
    "worker_port": "5000",
    "docker_runtime": ""
  },
  "errors": [],
  "worker_tail": [
    "2019/07/02 21:45:33 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:33 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:33 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:33 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:33 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:33 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:33 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/07/02 21:45:33 remove worker.pid",
    "2019/07/02 21:45:33 exiting",
    ""
  ]
}
POP conf: {'handler_cache_mb': 250, 'import_cache_mb': 250}
PUSH conf: {'import_cache_mb': 0, 'handler_cache_mb': 100, 'sandbox': 'docker'}
========================================
smoke_tests
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=7575, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7575\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 6.911561727523804,
  "total_seconds": 8.536319971084595,
  "stats": null,
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 8,
    "Create():ms.avg": 1718,
    "Create():ms.cnt": 4,
    "Destroy():ms.avg": 371,
    "Destroy():ms.cnt": 8,
    "Pause():ms.avg": 27,
    "Pause():ms.cnt": 8
  },
  "conf": {
    "Skip_pull_existing": false,
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-registry",
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "registry_cache_ms": 5000,
    "server_mode": "lambda",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "import_cache_mb": 0,
    "handler_cache_mb": 100,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "sandbox_config": {},
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "sandbox": "docker",
    "worker_port": "5000",
    "docker_runtime": ""
  },
  "errors": [],
  "worker_tail": [
    "Kill function: install2019/07/02 21:45:41 Destroy() [SB 1]",
    "2019/07/02 21:45:41 Destroy() [SB 1]",
    "Kill function: install22019/07/02 21:45:42 Destroy() [SB 2]",
    "2019/07/02 21:45:42 Destroy() [SB 2]",
    "Kill function: install32019/07/02 21:45:42 Destroy() [SB 3]",
    "2019/07/02 21:45:42 Destroy() [SB 3]",
    "2019/07/02 21:45:42 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/07/02 21:45:42 remove worker.pid",
    "2019/07/02 21:45:42 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=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=8580, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 8580\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 2.9009416103363037,
  "total_seconds": 3.9679746627807617,
  "stats": null,
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 8,
    "Create():ms.avg": 715,
    "Create():ms.cnt": 4,
    "Destroy():ms.avg": 211,
    "Destroy():ms.cnt": 8,
    "Pause():ms.avg": 25,
    "Pause():ms.cnt": 8
  },
  "conf": {
    "Skip_pull_existing": false,
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-registry",
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "registry_cache_ms": 5000,
    "server_mode": "lambda",
    "sandbox": "docker",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "import_cache_mb": 0,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "sandbox_config": {},
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "handler_cache_mb": 250,
    "worker_port": "5000",
    "docker_runtime": ""
  },
  "errors": [],
  "worker_tail": [
    "Kill function: install2019/07/02 21:45:45 Destroy() [SB 1]",
    "2019/07/02 21:45:45 Destroy() [SB 1]",
    "Kill function: install22019/07/02 21:45:45 Destroy() [SB 2]",
    "2019/07/02 21:45:45 Destroy() [SB 2]",
    "Kill function: install32019/07/02 21:45:46 Destroy() [SB 3]",
    "2019/07/02 21:45:46 Destroy() [SB 3]",
    "2019/07/02 21:45:46 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/07/02 21:45:46 remove worker.pid",
    "2019/07/02 21:45:46 exiting",
    ""
  ]
}
POP conf: {'import_cache_mb': 0, 'handler_cache_mb': 250, 'sandbox': 'docker'}
========================================
fork_bomb
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=9587, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 9587\n'
{
  "test": "fork_bomb",
  "params": {},
  "pass": true,
  "seconds": 0.08359932899475098,
  "total_seconds": 0.32196593284606934,
  "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": 146,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 3,
    "Create()/make-root-fs:ms.cnt": 2,
    "Create():ms.avg": 81,
    "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():ms.avg": 2,
    "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": 1,
    "move-to-cg-after-fork:ms.cnt": 1
  },
  "conf": {
    "Skip_pull_existing": false,
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-registry",
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "registry_cache_ms": 5000,
    "server_mode": "lambda",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "import_cache_mb": 239,
    "handler_cache_mb": 239,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "sandbox_config": {},
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "sandbox": "sock",
    "worker_port": "5000",
    "docker_runtime": ""
  },
  "errors": [],
  "worker_tail": [
    "2019/07/02 21:45:46 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:46 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:46 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:46 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:46 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:46 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:46 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/07/02 21:45:46 remove worker.pid",
    "2019/07/02 21:45:46 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=9630, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 9630\n'
{
  "test": "max_mem_alloc",
  "params": {},
  "pass": true,
  "seconds": 0.8250415325164795,
  "total_seconds": 1.0628399848937988,
  "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": 145,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 4,
    "Create()/make-root-fs:ms.cnt": 2,
    "Create():ms.avg": 81,
    "Create():ms.cnt": 2,
    "Destroy()/detach-root:ms.avg": 2,
    "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():ms.avg": 3,
    "Destroy():ms.cnt": 2,
    "Pause():ms.avg": 4,
    "Pause():ms.cnt": 1,
    "fork():ms.avg": 7,
    "fork():ms.cnt": 1,
    "forkRequest:ms.avg": 5,
    "forkRequest:ms.cnt": 1,
    "move-to-cg-after-fork:ms.avg": 1,
    "move-to-cg-after-fork:ms.cnt": 1
  },
  "conf": {
    "Skip_pull_existing": false,
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-registry",
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "registry_cache_ms": 5000,
    "server_mode": "lambda",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "import_cache_mb": 239,
    "handler_cache_mb": 239,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "sandbox_config": {},
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "sandbox": "sock",
    "worker_port": "5000",
    "docker_runtime": ""
  },
  "errors": [],
  "worker_tail": [
    "2019/07/02 21:45:47 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:47 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:47 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:47 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:47 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:47 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:45:47 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/07/02 21:45:47 remove worker.pid",
    "2019/07/02 21:45:47 exiting",
    ""
  ]
}
POP conf: {'registry': '/root/open-lambda/test-registry', 'startup_pkgs': ['parso', 'jedi', 'urllib3', 'idna', 'chardet', 'certifi', 'requests', 'simplejson']}
PUSH conf: {'server_mode': 'sock'}
========================================
sock_churn {'procs': 1, 'fork': True, 'baseline': 0, 'seconds': 15}
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=9712, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 9712\n'
{
  "test": "sock_churn",
  "params": {
    "procs": 1,
    "fork": true,
    "baseline": 0,
    "seconds": 15
  },
  "pass": true,
  "seconds": 15.172754049301147,
  "total_seconds": 15.394103765487671,
  "stats": {
    "sandboxes_per_sec": 45.86666666666667
  },
  "ol-stats": {
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 689,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 689,
    "Create()/fork-proc:ms.avg": 6,
    "Create()/fork-proc:ms.cnt": 688,
    "Create()/fresh-proc:ms.avg": 140,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 2,
    "Create()/make-root-fs:ms.cnt": 689,
    "Create():ms.avg": 10,
    "Create():ms.cnt": 689,
    "Destroy()/detach-root:ms.avg": 1,
    "Destroy()/detach-root:ms.cnt": 689,
    "Destroy()/kill-procs:ms.avg": 1,
    "Destroy()/kill-procs:ms.cnt": 689,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 689,
    "Destroy():ms.avg": 1,
    "Destroy():ms.cnt": 1377,
    "fork():ms.avg": 6,
    "fork():ms.cnt": 688,
    "forkRequest:ms.avg": 6,
    "forkRequest:ms.cnt": 688,
    "move-to-cg-after-fork:ms.avg": 0,
    "move-to-cg-after-fork:ms.cnt": 688
  },
  "conf": {
    "Skip_pull_existing": false,
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-dir/registry",
    "startup_pkgs": null,
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "registry_cache_ms": 5000,
    "server_mode": "sock",
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "import_cache_mb": 239,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "sandbox_config": {},
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "handler_cache_mb": 239,
    "worker_port": "5000",
    "docker_runtime": ""
  },
  "errors": [],
  "worker_tail": [
    "2019/07/02 21:46:03 remove /sys/fs/cgroup/freezer/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:46:03 remove /sys/fs/cgroup/hugetlb/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:46:03 remove /sys/fs/cgroup/memory/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:46:03 remove /sys/fs/cgroup/perf_event/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:46:03 remove /sys/fs/cgroup/systemd/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:46:03 remove /sys/fs/cgroup/pids/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:46:03 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/07/02 21:46:03 remove worker.pid",
    "2019/07/02 21:46:03 exiting",
    ""
  ]
}
========================================
sock_churn {'procs': 15, 'fork': True, 'baseline': 0, 'seconds': 15}
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=12495, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 12495\n'
{
  "test": "sock_churn",
  "params": {
    "procs": 15,
    "fork": true,
    "baseline": 0,
    "seconds": 15
  },
  "pass": true,
  "seconds": 15.508429527282715,
  "total_seconds": 15.738960027694702,
  "stats": {
    "sandboxes_per_sec": 46.266666666666666
  },
  "ol-stats": {
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 695,
    "Create()/acquire-mem:ms.avg": 95,
    "Create()/acquire-mem:ms.cnt": 695,
    "Create()/fork-proc:ms.avg": 42,
    "Create()/fork-proc:ms.cnt": 694,
    "Create()/fresh-proc:ms.avg": 135,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 2,
    "Create()/make-root-fs:ms.cnt": 695,
    "Create():ms.avg": 141,
    "Create():ms.cnt": 695,
    "Destroy()/detach-root:ms.avg": 2,
    "Destroy()/detach-root:ms.cnt": 695,
    "Destroy()/kill-procs:ms.avg": 7,
    "Destroy()/kill-procs:ms.cnt": 695,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 695,
    "Destroy():ms.avg": 6,
    "Destroy():ms.cnt": 1389,
    "fork():ms.avg": 42,
    "fork():ms.cnt": 694,
    "forkRequest:ms.avg": 10,
    "forkRequest:ms.cnt": 694,
    "move-to-cg-after-fork:ms.avg": 3,
    "move-to-cg-after-fork:ms.cnt": 694
  },
  "conf": {
    "Skip_pull_existing": false,
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-dir/registry",
    "startup_pkgs": null,
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "registry_cache_ms": 5000,
    "server_mode": "sock",
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "import_cache_mb": 239,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "sandbox_config": {},
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "handler_cache_mb": 239,
    "worker_port": "5000",
    "docker_runtime": ""
  },
  "errors": [],
  "worker_tail": [
    "2019/07/02 21:46:18 remove /sys/fs/cgroup/freezer/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:46:18 remove /sys/fs/cgroup/hugetlb/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:46:18 remove /sys/fs/cgroup/memory/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:46:18 remove /sys/fs/cgroup/perf_event/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:46:18 remove /sys/fs/cgroup/systemd/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:46:18 remove /sys/fs/cgroup/pids/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/07/02 21:46:18 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/07/02 21:46:18 remove worker.pid",
    "2019/07/02 21:46:18 exiting",
    ""
  ]
}
POP conf: {'server_mode': 'sock'}
PUSH conf: {'registry_cache_ms': 3000, 'registry': '/tmp/tmp0hx61cg6', 'sandbox': 'sock'}
========================================
update_code
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=15321, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 15321\n'
{
  "test": "update_code",
  "params": {},
  "pass": true,
  "seconds": 6.025326728820801,
  "total_seconds": 6.270956039428711,
  "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": 45,
    "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():ms.avg": 1,
    "Destroy():ms.cnt": 4,
    "Pause():ms.avg": 0,
    "Pause():ms.cnt": 1760,
    "Unpause():ms.avg": 0,
    "Unpause():ms.cnt": 1757,
    "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": {
    "Skip_pull_existing": false,
    "pip_mirror": "",
    "registry": "/tmp/tmp0hx61cg6",
    "startup_pkgs": null,
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "registry_cache_ms": 3000,
    "server_mode": "lambda",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "import_cache_mb": 239,
    "handler_cache_mb": 239,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "sandbox_config": {},
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "sandbox": "sock",
    "worker_port": "5000",
    "docker_runtime": ""
  },
  "errors": [],
  "worker_tail": [
    "2019/07/02 21:46:25 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:46:25 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:46:25 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:46:25 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:46:25 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:46:25 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:46:25 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/07/02 21:46:25 remove worker.pid",
    "2019/07/02 21:46:25 exiting",
    ""
  ]
}
POP conf: {'registry_cache_ms': 3000, 'registry': '/tmp/tmp0hx61cg6', 'sandbox': 'sock'}
PUSH conf: {'registry': '/root/open-lambda/test-registry', 'import_cache_mb': 250, 'handler_cache_mb': 250, 'sandbox': 'sock'}
========================================
stress_one_lambda {'procs': 1, 'seconds': 15}
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=15364, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 15364\n'
{
  "test": "stress_one_lambda",
  "params": {
    "procs": 1,
    "seconds": 15
  },
  "pass": true,
  "seconds": 15.044014692306519,
  "total_seconds": 15.277907371520996,
  "stats": {
    "reqs_per_sec": 294.26666666666665
  },
  "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": 150,
    "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():ms.avg": 1,
    "Destroy():ms.cnt": 4,
    "Pause():ms.avg": 0,
    "Pause():ms.cnt": 4414,
    "Unpause():ms.avg": 0,
    "Unpause():ms.cnt": 4411,
    "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": {
    "Skip_pull_existing": false,
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-registry",
    "startup_pkgs": null,
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "registry_cache_ms": 5000,
    "server_mode": "lambda",
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "import_cache_mb": 250,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "sandbox_config": {},
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "handler_cache_mb": 250,
    "worker_port": "5000",
    "docker_runtime": ""
  },
  "errors": [],
  "worker_tail": [
    "2019/07/02 21:46:40 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:46:40 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:46:40 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:46:40 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:46:40 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:46:40 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:46:40 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/07/02 21:46:40 remove worker.pid",
    "2019/07/02 21:46:40 exiting",
    ""
  ]
}
========================================
stress_one_lambda {'procs': 2, 'seconds': 15}
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=15411, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 15411\n'
{
  "test": "stress_one_lambda",
  "params": {
    "procs": 2,
    "seconds": 15
  },
  "pass": true,
  "seconds": 15.04878568649292,
  "total_seconds": 15.283893585205078,
  "stats": {
    "reqs_per_sec": 294.6
  },
  "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": 158,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 3,
    "Create()/make-root-fs:ms.cnt": 4,
    "Create():ms.avg": 50,
    "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():ms.avg": 1,
    "Destroy():ms.cnt": 4,
    "Pause():ms.avg": 0,
    "Pause():ms.cnt": 4076,
    "Unpause():ms.avg": 0,
    "Unpause():ms.cnt": 4073,
    "fork():ms.avg": 8,
    "fork():ms.cnt": 3,
    "forkRequest:ms.avg": 6,
    "forkRequest:ms.cnt": 3,
    "move-to-cg-after-fork:ms.avg": 1,
    "move-to-cg-after-fork:ms.cnt": 3
  },
  "conf": {
    "Skip_pull_existing": false,
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-registry",
    "startup_pkgs": null,
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "registry_cache_ms": 5000,
    "server_mode": "lambda",
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "import_cache_mb": 250,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "sandbox_config": {},
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "handler_cache_mb": 250,
    "worker_port": "5000",
    "docker_runtime": ""
  },
  "errors": [],
  "worker_tail": [
    "2019/07/02 21:46:55 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:46:55 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:46:55 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:46:55 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:46:55 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:46:55 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:46:55 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/07/02 21:46:55 remove worker.pid",
    "2019/07/02 21:46:55 exiting",
    ""
  ]
}
PUSH conf: {'handler_cache_mb': 500, 'import_cache_mb': 100}
========================================
stress_one_lambda {'procs': 8, 'seconds': 15}
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=15459, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 15459\n'
{
  "test": "stress_one_lambda",
  "params": {
    "procs": 8,
    "seconds": 15
  },
  "pass": true,
  "seconds": 15.077674150466919,
  "total_seconds": 15.309698343276978,
  "stats": {
    "reqs_per_sec": 262.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": 13,
    "Create()/fork-proc:ms.cnt": 3,
    "Create()/fresh-proc:ms.avg": 201,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 3,
    "Create()/make-root-fs:ms.cnt": 4,
    "Create():ms.avg": 64,
    "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():ms.avg": 1,
    "Destroy():ms.cnt": 4,
    "Pause():ms.avg": 0,
    "Pause():ms.cnt": 3357,
    "Unpause():ms.avg": 0,
    "Unpause():ms.cnt": 3354,
    "fork():ms.avg": 13,
    "fork():ms.cnt": 3,
    "forkRequest:ms.avg": 12,
    "forkRequest:ms.cnt": 3,
    "move-to-cg-after-fork:ms.avg": 0,
    "move-to-cg-after-fork:ms.cnt": 3
  },
  "conf": {
    "Skip_pull_existing": false,
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-registry",
    "startup_pkgs": null,
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "registry_cache_ms": 5000,
    "server_mode": "lambda",
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "import_cache_mb": 100,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "sandbox_config": {},
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "handler_cache_mb": 500,
    "worker_port": "5000",
    "docker_runtime": ""
  },
  "errors": [],
  "worker_tail": [
    "2019/07/02 21:47:11 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:47:11 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:47:11 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:47:11 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:47:11 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:47:11 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:47:11 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/07/02 21:47:11 remove worker.pid",
    "2019/07/02 21:47:11 exiting",
    ""
  ]
}
POP conf: {'handler_cache_mb': 500, 'import_cache_mb': 100}
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/tmpjbt3fmv0'}
========================================
call_each_once_exec {'alloc_mb': 1, 'lambda_count': 100}
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=15513, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 15513\n'
{
  "test": "call_each_once_exec",
  "params": {
    "alloc_mb": 1,
    "lambda_count": 100
  },
  "pass": true,
  "seconds": 2.562340259552002,
  "total_seconds": 2.8012659549713135,
  "stats": {
    "reqs_per_sec": 39.02720075033986
  },
  "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": 144,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 0,
    "Create()/make-root-fs:ms.cnt": 101,
    "Create():ms.avg": 8,
    "Create():ms.cnt": 101,
    "Destroy()/detach-root:ms.avg": 0,
    "Destroy()/detach-root:ms.cnt": 101,
    "Destroy()/kill-procs:ms.avg": 1,
    "Destroy()/kill-procs:ms.cnt": 101,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 101,
    "Destroy():ms.avg": 0,
    "Destroy():ms.cnt": 197,
    "Pause():ms.avg": 6,
    "Pause():ms.cnt": 100,
    "evict:ms.avg": 1,
    "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": 1,
    "move-to-cg-after-fork:ms.cnt": 100
  },
  "conf": {
    "Skip_pull_existing": false,
    "pip_mirror": "",
    "registry": "/tmp/tmpjbt3fmv0",
    "startup_pkgs": null,
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "registry_cache_ms": 5000,
    "server_mode": "lambda",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "import_cache_mb": 250,
    "handler_cache_mb": 250,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "sandbox_config": {},
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "sandbox": "sock",
    "worker_port": "5000",
    "docker_runtime": ""
  },
  "errors": [],
  "worker_tail": [
    "2019/07/02 21:47:13 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:47:13 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:47:13 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:47:13 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:47:13 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:47:13 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:47:13 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/07/02 21:47:13 remove worker.pid",
    "2019/07/02 21:47:13 exiting",
    ""
  ]
}
POP conf: {'registry': '/tmp/tmpjbt3fmv0'}
PUSH conf: {'registry': '/tmp/tmp01pjlmiz'}
========================================
call_each_once_exec {'alloc_mb': 10, 'lambda_count': 1000}
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=16041, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 16041\n'
{
  "test": "call_each_once_exec",
  "params": {
    "alloc_mb": 10,
    "lambda_count": 1000
  },
  "pass": true,
  "seconds": 38.46664071083069,
  "total_seconds": 38.81246995925903,
  "stats": {
    "reqs_per_sec": 25.99657196139966
  },
  "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": 8,
    "Create()/fork-proc:ms.cnt": 1000,
    "Create()/fresh-proc:ms.avg": 174,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 2,
    "Create()/make-root-fs:ms.cnt": 1001,
    "Create():ms.avg": 11,
    "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():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": 8,
    "fork():ms.cnt": 1000,
    "forkRequest:ms.avg": 7,
    "forkRequest:ms.cnt": 1000,
    "move-to-cg-after-fork:ms.avg": 0,
    "move-to-cg-after-fork:ms.cnt": 1000
  },
  "conf": {
    "Skip_pull_existing": false,
    "pip_mirror": "",
    "registry": "/tmp/tmp01pjlmiz",
    "startup_pkgs": null,
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "registry_cache_ms": 5000,
    "server_mode": "lambda",
    "sandbox": "sock",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "cluster_name": "/root/open-lambda/test-dir",
    "import_cache_mb": 250,
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "sandbox_config": {},
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "handler_cache_mb": 250,
    "worker_port": "5000",
    "docker_runtime": ""
  },
  "errors": [],
  "worker_tail": [
    "2019/07/02 21:47:52 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:47:52 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:47:52 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:47:52 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:47:52 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:47:52 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/07/02 21:47:52 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/07/02 21:47:52 remove worker.pid",
    "2019/07/02 21:47:52 exiting",
    ""
  ]
}
POP conf: {'registry': '/tmp/tmp01pjlmiz'}
POP conf: {'import_cache_mb': 250, 'handler_cache_mb': 250, 'sandbox': 'sock'}
PASSED: 17, FAILED: 0