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=7294, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7294\n'
{
  "test": "ping_test",
  "params": {},
  "pass": true,
  "seconds": 2.3992979526519775,
  "total_seconds": 17.796354055404663,
  "stats": {
    "pings_per_sec": 416.79169179921917
  },
  "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": 1581,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 5,
    "Create()/make-root-fs:ms.cnt": 1,
    "Create():ms.avg": 1586,
    "Create():ms.cnt": 1,
    "Destroy()/detach-root:ms.avg": 1,
    "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": 5,
    "Destroy():ms.cnt": 1
  },
  "conf": {
    "handler_cache_mb": 239,
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "sock",
    "Skip_pull_existing": false,
    "cluster_name": "/root/open-lambda/test-dir",
    "server_mode": "lambda",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "import_cache_mb": 239,
    "docker_runtime": "",
    "worker_port": "5000",
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "pip_mirror": "",
    "sandbox_config": {},
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "registry": "/root/open-lambda/test-registry"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 22:40:44 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:40:44 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:40:44 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:40:44 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:40:44 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:40:44 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:40:44 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/28 22:40:44 remove worker.pid",
    "2019/06/28 22:40:44 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=7365, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7365\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 1.591912031173706,
  "total_seconds": 9.554626941680908,
  "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": 136,
    "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": 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": 4,
    "Pause():ms.cnt": 4,
    "evict:ms.avg": 6,
    "evict:ms.cnt": 3
  },
  "conf": {
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "sock",
    "Skip_pull_existing": false,
    "cluster_name": "/root/open-lambda/test-dir",
    "server_mode": "lambda",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "handler_cache_mb": 100,
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "import_cache_mb": 0,
    "worker_port": "5000",
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "pip_mirror": "",
    "sandbox_config": {},
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "docker_runtime": "",
    "registry": "/root/open-lambda/test-registry"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 22:40:53 remove /sys/fs/cgroup/freezer/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:40:53 remove /sys/fs/cgroup/hugetlb/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:40:53 remove /sys/fs/cgroup/memory/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:40:53 remove /sys/fs/cgroup/perf_event/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:40:53 remove /sys/fs/cgroup/systemd/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:40:53 remove /sys/fs/cgroup/pids/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:40:53 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/28 22:40:53 remove worker.pid",
    "2019/06/28 22:40:53 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=7442, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7442\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 1.5411877632141113,
  "total_seconds": 9.617476224899292,
  "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": 138,
    "Create()/fresh-proc:ms.cnt": 4,
    "Create()/make-root-fs:ms.avg": 4,
    "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": 2,
    "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": {
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "sock",
    "Skip_pull_existing": false,
    "cluster_name": "/root/open-lambda/test-dir",
    "server_mode": "lambda",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "handler_cache_mb": 250,
    "pip_mirror": "",
    "import_cache_mb": 0,
    "worker_port": "5000",
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "docker_runtime": "",
    "registry": "/root/open-lambda/test-registry"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 22:41:03 remove /sys/fs/cgroup/freezer/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:41:03 remove /sys/fs/cgroup/hugetlb/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:41:03 remove /sys/fs/cgroup/memory/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:41:03 remove /sys/fs/cgroup/perf_event/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:41:03 remove /sys/fs/cgroup/systemd/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:41:03 remove /sys/fs/cgroup/pids/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:41:03 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/28 22:41:03 remove worker.pid",
    "2019/06/28 22:41:03 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=7519, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7519\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 0.9233253002166748,
  "total_seconds": 8.986479997634888,
  "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": 141,
    "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()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch: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": {
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "sock",
    "Skip_pull_existing": false,
    "cluster_name": "/root/open-lambda/test-dir",
    "server_mode": "lambda",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "handler_cache_mb": 100,
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "import_cache_mb": 250,
    "worker_port": "5000",
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "pip_mirror": "",
    "sandbox_config": {},
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "docker_runtime": "",
    "registry": "/root/open-lambda/test-registry"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 22:41:12 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:41:12 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:41:12 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:41:12 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:41:12 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:41:12 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:41:12 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/28 22:41:12 remove worker.pid",
    "2019/06/28 22:41:12 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=7601, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7601\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 0.8602256774902344,
  "total_seconds": 8.933110475540161,
  "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": 140,
    "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": 1,
    "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": 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": {
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "sock",
    "Skip_pull_existing": false,
    "cluster_name": "/root/open-lambda/test-dir",
    "server_mode": "lambda",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "handler_cache_mb": 250,
    "pip_mirror": "",
    "import_cache_mb": 250,
    "worker_port": "5000",
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "docker_runtime": "",
    "registry": "/root/open-lambda/test-registry"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 22:41:21 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:41:21 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:41:21 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:41:21 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:41:21 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:41:21 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:41:21 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/28 22:41:21 remove worker.pid",
    "2019/06/28 22:41:21 exiting",
    ""
  ]
}
POP conf: {'handler_cache_mb': 250, 'import_cache_mb': 250}
PUSH conf: {'sandbox': 'docker', '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=7683, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7683\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 6.479048013687134,
  "total_seconds": 16.739028453826904,
  "stats": null,
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 8,
    "Create():ms.avg": 1608,
    "Create():ms.cnt": 4,
    "Destroy():ms.avg": 581,
    "Destroy():ms.cnt": 8,
    "Pause():ms.avg": 27,
    "Pause():ms.cnt": 8
  },
  "conf": {
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "docker",
    "Skip_pull_existing": false,
    "cluster_name": "/root/open-lambda/test-dir",
    "server_mode": "lambda",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "handler_cache_mb": 100,
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "import_cache_mb": 0,
    "worker_port": "5000",
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "pip_mirror": "",
    "sandbox_config": {},
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "docker_runtime": "",
    "registry": "/root/open-lambda/test-registry"
  },
  "errors": [],
  "worker_tail": [
    "Kill function: install2019/06/28 22:41:37 Destroy() [SB 1]",
    "2019/06/28 22:41:37 Destroy() [SB 1]",
    "Kill function: install22019/06/28 22:41:37 Destroy() [SB 2]",
    "2019/06/28 22:41:37 Destroy() [SB 2]",
    "Kill function: install32019/06/28 22:41:37 Destroy() [SB 3]",
    "2019/06/28 22:41:37 Destroy() [SB 3]",
    "2019/06/28 22:41:38 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/28 22:41:38 remove worker.pid",
    "2019/06/28 22:41:38 exiting",
    ""
  ]
}
POP conf: {'sandbox': 'docker', 'handler_cache_mb': 100, 'import_cache_mb': 0}
PUSH conf: {'sandbox': 'docker', '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=8730, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 8730\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 3.5503997802734375,
  "total_seconds": 13.524750709533691,
  "stats": null,
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 8,
    "Create():ms.avg": 876,
    "Create():ms.cnt": 4,
    "Destroy():ms.avg": 320,
    "Destroy():ms.cnt": 8,
    "Pause():ms.avg": 28,
    "Pause():ms.cnt": 8
  },
  "conf": {
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "docker",
    "Skip_pull_existing": false,
    "cluster_name": "/root/open-lambda/test-dir",
    "server_mode": "lambda",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "handler_cache_mb": 250,
    "pip_mirror": "",
    "import_cache_mb": 0,
    "worker_port": "5000",
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "docker_runtime": "",
    "registry": "/root/open-lambda/test-registry"
  },
  "errors": [],
  "worker_tail": [
    "Kill function: install2019/06/28 22:41:50 Destroy() [SB 1]",
    "2019/06/28 22:41:50 Destroy() [SB 1]",
    "Kill function: install22019/06/28 22:41:50 Destroy() [SB 2]",
    "2019/06/28 22:41:50 Destroy() [SB 2]",
    "Kill function: install32019/06/28 22:41:51 Destroy() [SB 3]",
    "2019/06/28 22:41:51 Destroy() [SB 3]",
    "2019/06/28 22:41:51 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/28 22:41:51 remove worker.pid",
    "2019/06/28 22:41:51 exiting",
    ""
  ]
}
POP conf: {'sandbox': 'docker', 'handler_cache_mb': 250, 'import_cache_mb': 0}
========================================
fork_bomb
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=9768, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 9768\n'
{
  "test": "fork_bomb",
  "params": {},
  "pass": true,
  "seconds": 0.13651776313781738,
  "total_seconds": 8.19829797744751,
  "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": 10,
    "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": 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": 2,
    "Destroy():ms.cnt": 2,
    "Pause():ms.avg": 4,
    "Pause():ms.cnt": 1,
    "fork():ms.avg": 10,
    "fork():ms.cnt": 1,
    "forkRequest:ms.avg": 6,
    "forkRequest:ms.cnt": 1,
    "move-to-cg-after-fork:ms.avg": 3,
    "move-to-cg-after-fork:ms.cnt": 1
  },
  "conf": {
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "sock",
    "Skip_pull_existing": false,
    "cluster_name": "/root/open-lambda/test-dir",
    "server_mode": "lambda",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "handler_cache_mb": 239,
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "import_cache_mb": 239,
    "worker_port": "5000",
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "pip_mirror": "",
    "sandbox_config": {},
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "docker_runtime": "",
    "registry": "/root/open-lambda/test-registry"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 22:41:59 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:41:59 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:41:59 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:41:59 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:41:59 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:41:59 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:41:59 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/28 22:41:59 remove worker.pid",
    "2019/06/28 22:41:59 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=9842, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 9842\n'
{
  "test": "max_mem_alloc",
  "params": {},
  "pass": true,
  "seconds": 0.9167671203613281,
  "total_seconds": 8.983142852783203,
  "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": 9,
    "Create()/fork-proc:ms.cnt": 1,
    "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": 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": 1,
    "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": 9,
    "fork():ms.cnt": 1,
    "forkRequest:ms.avg": 8,
    "forkRequest:ms.cnt": 1,
    "move-to-cg-after-fork:ms.avg": 0,
    "move-to-cg-after-fork:ms.cnt": 1
  },
  "conf": {
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "sock",
    "Skip_pull_existing": false,
    "cluster_name": "/root/open-lambda/test-dir",
    "server_mode": "lambda",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "handler_cache_mb": 239,
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "import_cache_mb": 239,
    "worker_port": "5000",
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "pip_mirror": "",
    "sandbox_config": {},
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "docker_runtime": "",
    "registry": "/root/open-lambda/test-registry"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 22:42:08 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:42:08 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:42:08 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:42:08 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:42:08 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:42:08 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:42:08 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/28 22:42:08 remove worker.pid",
    "2019/06/28 22:42:08 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
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=9956, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 9956\n'
{
  "test": "sock_churn",
  "params": {
    "baseline": 0,
    "seconds": 15,
    "fork": true,
    "procs": 1
  },
  "pass": true,
  "seconds": 15.2408447265625,
  "total_seconds": 15.471332550048828,
  "stats": {
    "sandboxes_per_sec": 44.06666666666667
  },
  "ol-stats": {
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 662,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 662,
    "Create()/fork-proc:ms.avg": 7,
    "Create()/fork-proc:ms.cnt": 661,
    "Create()/fresh-proc:ms.avg": 136,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 2,
    "Create()/make-root-fs:ms.cnt": 662,
    "Create():ms.avg": 10,
    "Create():ms.cnt": 662,
    "Destroy()/detach-root:ms.avg": 1,
    "Destroy()/detach-root:ms.cnt": 662,
    "Destroy()/kill-procs:ms.avg": 1,
    "Destroy()/kill-procs:ms.cnt": 662,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 662,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 662,
    "Destroy():ms.avg": 1,
    "Destroy():ms.cnt": 1323,
    "fork():ms.avg": 7,
    "fork():ms.cnt": 661,
    "forkRequest:ms.avg": 6,
    "forkRequest:ms.cnt": 661,
    "move-to-cg-after-fork:ms.avg": 0,
    "move-to-cg-after-fork:ms.cnt": 661
  },
  "conf": {
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "sock",
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "Skip_pull_existing": false,
    "cluster_name": "/root/open-lambda/test-dir",
    "server_mode": "sock",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "pip_mirror": "",
    "import_cache_mb": 239,
    "worker_port": "5000",
    "handler_cache_mb": 239,
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "registry_cache_ms": 5000,
    "startup_pkgs": null,
    "docker_runtime": "",
    "registry": "/root/open-lambda/test-dir/registry"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 22:42:24 remove /sys/fs/cgroup/freezer/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:42:24 remove /sys/fs/cgroup/hugetlb/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:42:24 remove /sys/fs/cgroup/memory/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:42:24 remove /sys/fs/cgroup/perf_event/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:42:24 remove /sys/fs/cgroup/systemd/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:42:24 remove /sys/fs/cgroup/pids/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:42:24 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/28 22:42:24 remove worker.pid",
    "2019/06/28 22:42:24 exiting",
    ""
  ]
}
========================================
sock_churn
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=12631, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 12631\n'
{
  "test": "sock_churn",
  "params": {
    "baseline": 0,
    "seconds": 15,
    "fork": true,
    "procs": 15
  },
  "pass": true,
  "seconds": 15.405477285385132,
  "total_seconds": 15.640486478805542,
  "stats": {
    "sandboxes_per_sec": 45.4
  },
  "ol-stats": {
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 682,
    "Create()/acquire-mem:ms.avg": 110,
    "Create()/acquire-mem:ms.cnt": 682,
    "Create()/fork-proc:ms.avg": 44,
    "Create()/fork-proc:ms.cnt": 681,
    "Create()/fresh-proc:ms.avg": 136,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 4,
    "Create()/make-root-fs:ms.cnt": 682,
    "Create():ms.avg": 160,
    "Create():ms.cnt": 682,
    "Destroy()/detach-root:ms.avg": 2,
    "Destroy()/detach-root:ms.cnt": 682,
    "Destroy()/kill-procs:ms.avg": 7,
    "Destroy()/kill-procs:ms.cnt": 682,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 682,
    "Destroy()/remove-scratch:ms.avg": 1,
    "Destroy()/remove-scratch:ms.cnt": 682,
    "Destroy():ms.avg": 7,
    "Destroy():ms.cnt": 1363,
    "fork():ms.avg": 44,
    "fork():ms.cnt": 681,
    "forkRequest:ms.avg": 11,
    "forkRequest:ms.cnt": 681,
    "move-to-cg-after-fork:ms.avg": 4,
    "move-to-cg-after-fork:ms.cnt": 681
  },
  "conf": {
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "sock",
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "Skip_pull_existing": false,
    "cluster_name": "/root/open-lambda/test-dir",
    "server_mode": "sock",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "pip_mirror": "",
    "import_cache_mb": 239,
    "worker_port": "5000",
    "handler_cache_mb": 239,
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "registry_cache_ms": 5000,
    "startup_pkgs": null,
    "docker_runtime": "",
    "registry": "/root/open-lambda/test-dir/registry"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 22:42:39 remove /sys/fs/cgroup/freezer/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:42:39 remove /sys/fs/cgroup/hugetlb/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:42:39 remove /sys/fs/cgroup/memory/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:42:39 remove /sys/fs/cgroup/perf_event/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:42:39 remove /sys/fs/cgroup/systemd/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:42:39 remove /sys/fs/cgroup/pids/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/28 22:42:39 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/28 22:42:39 remove worker.pid",
    "2019/06/28 22:42:39 exiting",
    ""
  ]
}
POP conf: {'server_mode': 'sock'}
PUSH conf: {'sandbox': 'sock', 'registry_cache_ms': 3000, 'registry': '/tmp/tmpffk1co8l'}
========================================
update_code
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=15406, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 15406\n'
{
  "test": "update_code",
  "params": {},
  "pass": true,
  "seconds": 6.025238752365112,
  "total_seconds": 6.266253709793091,
  "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": 9,
    "Create()/fork-proc:ms.cnt": 3,
    "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": 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": 2,
    "Destroy():ms.cnt": 4,
    "Pause():ms.avg": 0,
    "Pause():ms.cnt": 1751,
    "Unpause():ms.avg": 0,
    "Unpause():ms.cnt": 1748,
    "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": {
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "sock",
    "Skip_pull_existing": false,
    "cluster_name": "/root/open-lambda/test-dir",
    "server_mode": "lambda",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "handler_cache_mb": 239,
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "import_cache_mb": 239,
    "worker_port": "5000",
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "pip_mirror": "",
    "sandbox_config": {},
    "registry_cache_ms": 3000,
    "startup_pkgs": null,
    "docker_runtime": "",
    "registry": "/tmp/tmpffk1co8l"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 22:42:46 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:42:46 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:42:46 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:42:46 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:42:46 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:42:46 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:42:46 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/28 22:42:46 remove worker.pid",
    "2019/06/28 22:42:46 exiting",
    ""
  ]
}
POP conf: {'sandbox': 'sock', 'registry_cache_ms': 3000, 'registry': '/tmp/tmpffk1co8l'}
PUSH conf: {'sandbox': 'sock', 'handler_cache_mb': 250, 'registry': '/root/open-lambda/test-registry', 'import_cache_mb': 250}
========================================
stress_one_lambda
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=15449, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 15449\n'
{
  "test": "stress_one_lambda",
  "params": {
    "seconds": 15,
    "procs": 1
  },
  "pass": true,
  "seconds": 15.046229839324951,
  "total_seconds": 15.2804594039917,
  "stats": {
    "reqs_per_sec": 293.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": 9,
    "Create()/fork-proc:ms.cnt": 3,
    "Create()/fresh-proc:ms.avg": 148,
    "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": 4399,
    "Unpause():ms.avg": 0,
    "Unpause():ms.cnt": 4396,
    "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": {
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "sock",
    "Skip_pull_existing": false,
    "cluster_name": "/root/open-lambda/test-dir",
    "server_mode": "lambda",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "handler_cache_mb": 250,
    "pip_mirror": "",
    "import_cache_mb": 250,
    "worker_port": "5000",
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "registry_cache_ms": 5000,
    "startup_pkgs": null,
    "docker_runtime": "",
    "registry": "/root/open-lambda/test-registry"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 22:43:01 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:01 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:01 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:01 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:01 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:01 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:01 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/28 22:43:01 remove worker.pid",
    "2019/06/28 22:43:01 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=15496, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 15496\n'
{
  "test": "stress_one_lambda",
  "params": {
    "seconds": 15,
    "procs": 2
  },
  "pass": true,
  "seconds": 15.047831296920776,
  "total_seconds": 15.282846212387085,
  "stats": {
    "reqs_per_sec": 293.3333333333333
  },
  "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": 157,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 3,
    "Create()/make-root-fs:ms.cnt": 4,
    "Create():ms.avg": 51,
    "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": 2,
    "Destroy():ms.cnt": 4,
    "Pause():ms.avg": 0,
    "Pause():ms.cnt": 3977,
    "Unpause():ms.avg": 0,
    "Unpause():ms.cnt": 3974,
    "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": {
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "sock",
    "Skip_pull_existing": false,
    "cluster_name": "/root/open-lambda/test-dir",
    "server_mode": "lambda",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "handler_cache_mb": 250,
    "pip_mirror": "",
    "import_cache_mb": 250,
    "worker_port": "5000",
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "registry_cache_ms": 5000,
    "startup_pkgs": null,
    "docker_runtime": "",
    "registry": "/root/open-lambda/test-registry"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 22:43:16 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:16 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:16 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:16 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:16 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:16 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:16 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/28 22:43:16 remove worker.pid",
    "2019/06/28 22:43:16 exiting",
    ""
  ]
}
PUSH conf: {'handler_cache_mb': 500, 'import_cache_mb': 100}
========================================
stress_one_lambda
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=15544, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 15544\n'
{
  "test": "stress_one_lambda",
  "params": {
    "seconds": 15,
    "procs": 8
  },
  "pass": true,
  "seconds": 15.078265905380249,
  "total_seconds": 15.311950206756592,
  "stats": {
    "reqs_per_sec": 265.93333333333334
  },
  "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": 197,
    "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": 2,
    "Destroy():ms.cnt": 4,
    "Pause():ms.avg": 0,
    "Pause():ms.cnt": 3396,
    "Unpause():ms.avg": 0,
    "Unpause():ms.cnt": 3393,
    "fork():ms.avg": 8,
    "fork():ms.cnt": 3,
    "forkRequest:ms.avg": 8,
    "forkRequest:ms.cnt": 3,
    "move-to-cg-after-fork:ms.avg": 0,
    "move-to-cg-after-fork:ms.cnt": 3
  },
  "conf": {
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "sock",
    "Skip_pull_existing": false,
    "cluster_name": "/root/open-lambda/test-dir",
    "server_mode": "lambda",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "handler_cache_mb": 500,
    "pip_mirror": "",
    "import_cache_mb": 100,
    "worker_port": "5000",
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "registry_cache_ms": 5000,
    "startup_pkgs": null,
    "docker_runtime": "",
    "registry": "/root/open-lambda/test-registry"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 22:43:32 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:32 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:32 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:32 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:32 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:32 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:32 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/28 22:43:32 remove worker.pid",
    "2019/06/28 22:43:32 exiting",
    ""
  ]
}
POP conf: {'handler_cache_mb': 500, 'import_cache_mb': 100}
POP conf: {'sandbox': 'sock', 'handler_cache_mb': 250, 'registry': '/root/open-lambda/test-registry', 'import_cache_mb': 250}
PUSH conf: {'sandbox': 'sock', 'handler_cache_mb': 250, 'import_cache_mb': 250}
PUSH conf: {'registry': '/tmp/tmpo8p8fkvu'}
========================================
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=15599, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 15599\n'
{
  "test": "call_each_once_exec",
  "params": {
    "alloc_mb": 1,
    "lambda_count": 100
  },
  "pass": true,
  "seconds": 2.60536789894104,
  "total_seconds": 2.8481345176696777,
  "stats": {
    "reqs_per_sec": 38.38269920449065
  },
  "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": 142,
    "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": 2,
    "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": 1,
    "Destroy():ms.cnt": 197,
    "Pause():ms.avg": 6,
    "Pause():ms.cnt": 100,
    "evict:ms.avg": 2,
    "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": {
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "sock",
    "Skip_pull_existing": false,
    "cluster_name": "/root/open-lambda/test-dir",
    "server_mode": "lambda",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "handler_cache_mb": 250,
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "import_cache_mb": 250,
    "worker_port": "5000",
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "pip_mirror": "",
    "sandbox_config": {},
    "registry_cache_ms": 5000,
    "startup_pkgs": null,
    "docker_runtime": "",
    "registry": "/tmp/tmpo8p8fkvu"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 22:43:34 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:34 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:34 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:34 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:34 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:34 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:43:34 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/28 22:43:34 remove worker.pid",
    "2019/06/28 22:43:34 exiting",
    ""
  ]
}
POP conf: {'registry': '/tmp/tmpo8p8fkvu'}
PUSH conf: {'registry': '/tmp/tmplfm45xxx'}
========================================
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=16128, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 16128\n'
{
  "test": "call_each_once_exec",
  "params": {
    "alloc_mb": 10,
    "lambda_count": 1000
  },
  "pass": true,
  "seconds": 37.84111523628235,
  "total_seconds": 38.18775963783264,
  "stats": {
    "reqs_per_sec": 26.426304608766316
  },
  "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": 176,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 2,
    "Create()/make-root-fs:ms.cnt": 1001,
    "Create():ms.avg": 12,
    "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": 7,
    "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": 1,
    "move-to-cg-after-fork:ms.cnt": 1000
  },
  "conf": {
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "sock",
    "Skip_pull_existing": false,
    "cluster_name": "/root/open-lambda/test-dir",
    "server_mode": "lambda",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "handler_cache_mb": 250,
    "pip_mirror": "",
    "import_cache_mb": 250,
    "worker_port": "5000",
    "sock_cgroups": {
      "max_mem_mb": 50,
      "max_procs": 10
    },
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "registry_cache_ms": 5000,
    "startup_pkgs": null,
    "docker_runtime": "",
    "registry": "/tmp/tmplfm45xxx"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/28 22:44:13 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:44:13 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:44:13 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:44:13 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:44:13 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:44:13 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/28 22:44:13 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/28 22:44:13 remove worker.pid",
    "2019/06/28 22:44:13 exiting",
    ""
  ]
}
POP conf: {'registry': '/tmp/tmplfm45xxx'}
POP conf: {'sandbox': 'sock', 'handler_cache_mb': 250, 'import_cache_mb': 250}
PASSED: 17, FAILED: 0