python3 -u test.py
RUN ./ol new -p=test-dir
b'Init OL dir at /root/open-lambda/test-dir\nCreate lambda base at /root/open-lambda/test-dir/lambda (may take several minutes)\nWorking Directory: /root/open-lambda/test-dir\n\nWorker Defaults: \n{\n\t"registry": "/root/open-lambda/test-dir/registry",\n\t"registry_cache_ms": 5000,\n\t"sandbox": "sock",\n\t"cluster_name": "/root/open-lambda/test-dir",\n\t"pip_mirror": "",\n\t"Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",\n\t"handler_cache_mb": 239,\n\t"import_cache_mb": 239,\n\t"server_mode": "lambda",\n\t"worker_dir": "/root/open-lambda/test-dir/worker",\n\t"SOCK_base_path": "/root/open-lambda/test-dir/lambda",\n\t"worker_port": "5000",\n\t"Skip_pull_existing": false,\n\t"sandbox_config": {},\n\t"startup_pkgs": null,\n\t"docker_runtime": "",\n\t"sock_cgroups": {\n\t\t"max_procs": 10,\n\t\t"max_mem_mb": 50\n\t}\n}\n\nYou may modify the defaults here: /root/open-lambda/test-dir/config.json\n\nYou may now start a server using the "ol worker" command\n'
PUSH conf: {'startup_pkgs': ['parso', 'jedi', 'urllib3', 'idna', 'chardet', 'certifi', 'requests', 'simplejson'], 'registry': '/root/open-lambda/test-registry'}
========================================
ping_test
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=7287, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7287\n'
{
  "test": "ping_test",
  "params": {},
  "pass": true,
  "seconds": 2.3669047355651855,
  "total_seconds": 18.55015730857849,
  "stats": {
    "pings_per_sec": 422.4960737608159
  },
  "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": 1456,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 4,
    "Create()/make-root-fs:ms.cnt": 1,
    "Create():ms.avg": 1461,
    "Create():ms.cnt": 1,
    "Destroy()/detach-root:ms.avg": 0,
    "Destroy()/detach-root:ms.cnt": 1,
    "Destroy()/kill-procs:ms.avg": 1,
    "Destroy()/kill-procs:ms.cnt": 1,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 1,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 1,
    "Destroy():ms.avg": 5,
    "Destroy():ms.cnt": 1
  },
  "conf": {
    "worker_port": "5000",
    "import_cache_mb": 239,
    "registry": "/root/open-lambda/test-registry",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "handler_cache_mb": 239,
    "docker_runtime": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "server_mode": "lambda",
    "Skip_pull_existing": false,
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "sandbox_config": {},
    "pip_mirror": "",
    "sandbox": "sock"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/29 21:42:49 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:42:49 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:42:49 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:42:49 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:42:49 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:42:49 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:42:49 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/29 21:42:49 remove worker.pid",
    "2019/06/29 21:42:49 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=7358, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7358\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 1.5672237873077393,
  "total_seconds": 9.629678726196289,
  "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": 1,
    "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()/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": {
    "worker_port": "5000",
    "import_cache_mb": 0,
    "registry": "/root/open-lambda/test-registry",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "handler_cache_mb": 100,
    "docker_runtime": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "server_mode": "lambda",
    "Skip_pull_existing": false,
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "sandbox_config": {},
    "pip_mirror": "",
    "sandbox": "sock"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/29 21:42:58 remove /sys/fs/cgroup/freezer/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:42:58 remove /sys/fs/cgroup/hugetlb/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:42:58 remove /sys/fs/cgroup/memory/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:42:58 remove /sys/fs/cgroup/perf_event/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:42:58 remove /sys/fs/cgroup/systemd/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:42:58 remove /sys/fs/cgroup/pids/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:42:58 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/29 21:42:58 remove worker.pid",
    "2019/06/29 21:42:58 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=7436, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7436\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 1.5008347034454346,
  "total_seconds": 9.476707935333252,
  "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": 145,
    "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": {
    "worker_port": "5000",
    "import_cache_mb": 0,
    "registry": "/root/open-lambda/test-registry",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "handler_cache_mb": 250,
    "docker_runtime": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "server_mode": "lambda",
    "Skip_pull_existing": false,
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "sandbox_config": {},
    "pip_mirror": "",
    "sandbox": "sock"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/29 21:43:08 remove /sys/fs/cgroup/freezer/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:43:08 remove /sys/fs/cgroup/hugetlb/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:43:08 remove /sys/fs/cgroup/memory/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:43:08 remove /sys/fs/cgroup/perf_event/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:43:08 remove /sys/fs/cgroup/systemd/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:43:08 remove /sys/fs/cgroup/pids/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:43:08 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/29 21:43:08 remove worker.pid",
    "2019/06/29 21:43:08 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=7514, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7514\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 0.8906247615814209,
  "total_seconds": 8.952597379684448,
  "stats": null,
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 4,
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 5,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 5,
    "Create()/fork-proc:ms.avg": 7,
    "Create()/fork-proc:ms.cnt": 4,
    "Create()/fresh-proc:ms.avg": 146,
    "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": 0,
    "Destroy()/kill-procs:ms.cnt": 5,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 5,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 5,
    "Destroy():ms.avg": 1,
    "Destroy():ms.cnt": 8,
    "Pause():ms.avg": 3,
    "Pause():ms.cnt": 4,
    "evict:ms.avg": 0,
    "evict:ms.cnt": 3,
    "fork():ms.avg": 7,
    "fork():ms.cnt": 4,
    "forkRequest:ms.avg": 7,
    "forkRequest:ms.cnt": 4,
    "move-to-cg-after-fork:ms.avg": 0,
    "move-to-cg-after-fork:ms.cnt": 4
  },
  "conf": {
    "worker_port": "5000",
    "import_cache_mb": 250,
    "registry": "/root/open-lambda/test-registry",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "handler_cache_mb": 100,
    "docker_runtime": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "server_mode": "lambda",
    "Skip_pull_existing": false,
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "sandbox_config": {},
    "pip_mirror": "",
    "sandbox": "sock"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/29 21:43:17 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:43:17 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:43:17 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:43:17 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:43:17 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:43:17 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:43:17 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/29 21:43:17 remove worker.pid",
    "2019/06/29 21:43:17 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=7596, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7596\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 0.8617618083953857,
  "total_seconds": 8.93160605430603,
  "stats": null,
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 4,
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 5,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 5,
    "Create()/fork-proc:ms.avg": 7,
    "Create()/fork-proc:ms.cnt": 4,
    "Create()/fresh-proc:ms.avg": 141,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 3,
    "Create()/make-root-fs:ms.cnt": 5,
    "Create():ms.avg": 38,
    "Create():ms.cnt": 5,
    "Destroy()/detach-root:ms.avg": 0,
    "Destroy()/detach-root:ms.cnt": 5,
    "Destroy()/kill-procs:ms.avg": 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": 7,
    "fork():ms.cnt": 4,
    "forkRequest:ms.avg": 6,
    "forkRequest:ms.cnt": 4,
    "move-to-cg-after-fork:ms.avg": 0,
    "move-to-cg-after-fork:ms.cnt": 4
  },
  "conf": {
    "worker_port": "5000",
    "import_cache_mb": 250,
    "registry": "/root/open-lambda/test-registry",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "handler_cache_mb": 250,
    "docker_runtime": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "server_mode": "lambda",
    "Skip_pull_existing": false,
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "sandbox_config": {},
    "pip_mirror": "",
    "sandbox": "sock"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/29 21:43:26 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:43:26 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:43:26 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:43:26 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:43:26 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:43:26 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:43:26 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/29 21:43:26 remove worker.pid",
    "2019/06/29 21:43:26 exiting",
    ""
  ]
}
POP conf: {'handler_cache_mb': 250, 'import_cache_mb': 250}
PUSH conf: {'handler_cache_mb': 100, 'import_cache_mb': 0, '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=7678, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 7678\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 7.3837807178497314,
  "total_seconds": 16.641170740127563,
  "stats": null,
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 8,
    "Create():ms.avg": 1836,
    "Create():ms.cnt": 4,
    "Destroy():ms.avg": 344,
    "Destroy():ms.cnt": 8,
    "Pause():ms.avg": 27,
    "Pause():ms.cnt": 8
  },
  "conf": {
    "worker_port": "5000",
    "import_cache_mb": 0,
    "registry": "/root/open-lambda/test-registry",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "handler_cache_mb": 100,
    "docker_runtime": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "server_mode": "lambda",
    "Skip_pull_existing": false,
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "sandbox_config": {},
    "pip_mirror": "",
    "sandbox": "docker"
  },
  "errors": [],
  "worker_tail": [
    "Kill function: install2019/06/29 21:43:42 Destroy() [SB 1]",
    "2019/06/29 21:43:42 Destroy() [SB 1]",
    "Kill function: install22019/06/29 21:43:42 Destroy() [SB 2]",
    "2019/06/29 21:43:42 Destroy() [SB 2]",
    "Kill function: install32019/06/29 21:43:42 Destroy() [SB 3]",
    "2019/06/29 21:43:42 Destroy() [SB 3]",
    "2019/06/29 21:43:42 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/29 21:43:42 remove worker.pid",
    "2019/06/29 21:43:42 exiting",
    ""
  ]
}
POP conf: {'handler_cache_mb': 100, 'import_cache_mb': 0, 'sandbox': 'docker'}
PUSH conf: {'handler_cache_mb': 250, 'import_cache_mb': 0, '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=8718, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 8718\n'
{
  "test": "smoke_tests",
  "params": {},
  "pass": true,
  "seconds": 2.8624353408813477,
  "total_seconds": 11.666658639907837,
  "stats": null,
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 8,
    "Create():ms.avg": 705,
    "Create():ms.cnt": 4,
    "Destroy():ms.avg": 204,
    "Destroy():ms.cnt": 8,
    "Pause():ms.avg": 24,
    "Pause():ms.cnt": 8
  },
  "conf": {
    "worker_port": "5000",
    "import_cache_mb": 0,
    "registry": "/root/open-lambda/test-registry",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "handler_cache_mb": 250,
    "docker_runtime": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "server_mode": "lambda",
    "Skip_pull_existing": false,
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "sandbox_config": {},
    "pip_mirror": "",
    "sandbox": "docker"
  },
  "errors": [],
  "worker_tail": [
    "Kill function: install2019/06/29 21:43:53 Destroy() [SB 1]",
    "2019/06/29 21:43:53 Destroy() [SB 1]",
    "Kill function: install22019/06/29 21:43:53 Destroy() [SB 2]",
    "2019/06/29 21:43:53 Destroy() [SB 2]",
    "Kill function: install32019/06/29 21:43:54 Destroy() [SB 3]",
    "2019/06/29 21:43:54 Destroy() [SB 3]",
    "2019/06/29 21:43:54 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/29 21:43:54 remove worker.pid",
    "2019/06/29 21:43:54 exiting",
    ""
  ]
}
POP conf: {'handler_cache_mb': 250, 'import_cache_mb': 0, '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=9764, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 9764\n'
{
  "test": "fork_bomb",
  "params": {},
  "pass": true,
  "seconds": 0.09418654441833496,
  "total_seconds": 8.158081769943237,
  "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": 141,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 4,
    "Create()/make-root-fs:ms.cnt": 2,
    "Create():ms.avg": 79,
    "Create():ms.cnt": 2,
    "Destroy()/detach-root:ms.avg": 1,
    "Destroy()/detach-root:ms.cnt": 2,
    "Destroy()/kill-procs:ms.avg": 0,
    "Destroy()/kill-procs:ms.cnt": 2,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 2,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 2,
    "Destroy():ms.avg": 2,
    "Destroy():ms.cnt": 2,
    "Pause():ms.avg": 4,
    "Pause():ms.cnt": 1,
    "fork():ms.avg": 8,
    "fork():ms.cnt": 1,
    "forkRequest:ms.avg": 6,
    "forkRequest:ms.cnt": 1,
    "move-to-cg-after-fork:ms.avg": 1,
    "move-to-cg-after-fork:ms.cnt": 1
  },
  "conf": {
    "worker_port": "5000",
    "import_cache_mb": 239,
    "registry": "/root/open-lambda/test-registry",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "handler_cache_mb": 239,
    "docker_runtime": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "server_mode": "lambda",
    "Skip_pull_existing": false,
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "sandbox_config": {},
    "pip_mirror": "",
    "sandbox": "sock"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/29 21:44:02 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:44:02 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:44:02 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:44:02 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:44:02 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:44:02 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:44:02 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/29 21:44:02 remove worker.pid",
    "2019/06/29 21:44:02 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=9838, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 9838\n'
{
  "test": "max_mem_alloc",
  "params": {},
  "pass": true,
  "seconds": 0.9036717414855957,
  "total_seconds": 8.868010997772217,
  "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": 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": 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": 8,
    "fork():ms.cnt": 1,
    "forkRequest:ms.avg": 5,
    "forkRequest:ms.cnt": 1,
    "move-to-cg-after-fork:ms.avg": 2,
    "move-to-cg-after-fork:ms.cnt": 1
  },
  "conf": {
    "worker_port": "5000",
    "import_cache_mb": 239,
    "registry": "/root/open-lambda/test-registry",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "handler_cache_mb": 239,
    "docker_runtime": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "server_mode": "lambda",
    "Skip_pull_existing": false,
    "registry_cache_ms": 5000,
    "startup_pkgs": [
      "parso",
      "jedi",
      "urllib3",
      "idna",
      "chardet",
      "certifi",
      "requests",
      "simplejson"
    ],
    "sandbox_config": {},
    "pip_mirror": "",
    "sandbox": "sock"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/29 21:44:11 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:44:11 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:44:11 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:44:11 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:44:11 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:44:11 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:44:11 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/29 21:44:11 remove worker.pid",
    "2019/06/29 21:44:11 exiting",
    ""
  ]
}
POP conf: {'startup_pkgs': ['parso', 'jedi', 'urllib3', 'idna', 'chardet', 'certifi', 'requests', 'simplejson'], 'registry': '/root/open-lambda/test-registry'}
PUSH conf: {'server_mode': 'sock'}
========================================
sock_churn
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=9953, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 9953\n'
{
  "test": "sock_churn",
  "params": {
    "seconds": 15,
    "fork": true,
    "procs": 1,
    "baseline": 0
  },
  "pass": true,
  "seconds": 15.157860279083252,
  "total_seconds": 15.378708124160767,
  "stats": {
    "sandboxes_per_sec": 45.53333333333333
  },
  "ol-stats": {
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 684,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 684,
    "Create()/fork-proc:ms.avg": 6,
    "Create()/fork-proc:ms.cnt": 683,
    "Create()/fresh-proc:ms.avg": 132,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 2,
    "Create()/make-root-fs:ms.cnt": 684,
    "Create():ms.avg": 10,
    "Create():ms.cnt": 684,
    "Destroy()/detach-root:ms.avg": 1,
    "Destroy()/detach-root:ms.cnt": 684,
    "Destroy()/kill-procs:ms.avg": 1,
    "Destroy()/kill-procs:ms.cnt": 684,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 684,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 684,
    "Destroy():ms.avg": 1,
    "Destroy():ms.cnt": 1367,
    "fork():ms.avg": 6,
    "fork():ms.cnt": 683,
    "forkRequest:ms.avg": 6,
    "forkRequest:ms.cnt": 683,
    "move-to-cg-after-fork:ms.avg": 0,
    "move-to-cg-after-fork:ms.cnt": 683
  },
  "conf": {
    "worker_port": "5000",
    "import_cache_mb": 239,
    "registry": "/root/open-lambda/test-dir/registry",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "handler_cache_mb": 239,
    "docker_runtime": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "server_mode": "sock",
    "Skip_pull_existing": false,
    "registry_cache_ms": 5000,
    "startup_pkgs": null,
    "sandbox_config": {},
    "pip_mirror": "",
    "sandbox": "sock"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/29 21:44:26 remove /sys/fs/cgroup/freezer/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:44:26 remove /sys/fs/cgroup/hugetlb/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:44:26 remove /sys/fs/cgroup/memory/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:44:26 remove /sys/fs/cgroup/perf_event/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:44:26 remove /sys/fs/cgroup/systemd/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:44:26 remove /sys/fs/cgroup/pids/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:44:26 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/29 21:44:26 remove worker.pid",
    "2019/06/29 21:44:26 exiting",
    ""
  ]
}
========================================
sock_churn
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=12716, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 12716\n'
{
  "test": "sock_churn",
  "params": {
    "seconds": 15,
    "fork": true,
    "procs": 15,
    "baseline": 0
  },
  "pass": true,
  "seconds": 15.496036767959595,
  "total_seconds": 15.720364332199097,
  "stats": {
    "sandboxes_per_sec": 46.2
  },
  "ol-stats": {
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 694,
    "Create()/acquire-mem:ms.avg": 104,
    "Create()/acquire-mem:ms.cnt": 694,
    "Create()/fork-proc:ms.avg": 40,
    "Create()/fork-proc:ms.cnt": 693,
    "Create()/fresh-proc:ms.avg": 132,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 4,
    "Create()/make-root-fs:ms.cnt": 694,
    "Create():ms.avg": 151,
    "Create():ms.cnt": 694,
    "Destroy()/detach-root:ms.avg": 2,
    "Destroy()/detach-root:ms.cnt": 694,
    "Destroy()/kill-procs:ms.avg": 8,
    "Destroy()/kill-procs:ms.cnt": 694,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 694,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 694,
    "Destroy():ms.avg": 7,
    "Destroy():ms.cnt": 1387,
    "fork():ms.avg": 40,
    "fork():ms.cnt": 693,
    "forkRequest:ms.avg": 10,
    "forkRequest:ms.cnt": 693,
    "move-to-cg-after-fork:ms.avg": 4,
    "move-to-cg-after-fork:ms.cnt": 693
  },
  "conf": {
    "worker_port": "5000",
    "import_cache_mb": 239,
    "registry": "/root/open-lambda/test-dir/registry",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "handler_cache_mb": 239,
    "docker_runtime": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "server_mode": "sock",
    "Skip_pull_existing": false,
    "registry_cache_ms": 5000,
    "startup_pkgs": null,
    "sandbox_config": {},
    "pip_mirror": "",
    "sandbox": "sock"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/29 21:44:42 remove /sys/fs/cgroup/freezer/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:44:42 remove /sys/fs/cgroup/hugetlb/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:44:42 remove /sys/fs/cgroup/memory/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:44:42 remove /sys/fs/cgroup/perf_event/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:44:42 remove /sys/fs/cgroup/systemd/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:44:42 remove /sys/fs/cgroup/pids/worker-sock-handlers [CGROUP POOL worker-sock-handlers]",
    "2019/06/29 21:44:42 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/29 21:44:42 remove worker.pid",
    "2019/06/29 21:44:42 exiting",
    ""
  ]
}
POP conf: {'server_mode': 'sock'}
PUSH conf: {'registry_cache_ms': 3000, 'sandbox': 'sock', 'registry': '/tmp/tmpsqzdeewf'}
========================================
update_code
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=15539, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 15539\n'
{
  "test": "update_code",
  "params": {},
  "pass": true,
  "seconds": 6.027031183242798,
  "total_seconds": 6.268936634063721,
  "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": 140,
    "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()/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": 1796,
    "Unpause():ms.avg": 0,
    "Unpause():ms.cnt": 1793,
    "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": {
    "worker_port": "5000",
    "import_cache_mb": 239,
    "registry": "/tmp/tmpsqzdeewf",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "handler_cache_mb": 239,
    "docker_runtime": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "server_mode": "lambda",
    "Skip_pull_existing": false,
    "registry_cache_ms": 3000,
    "startup_pkgs": null,
    "sandbox_config": {},
    "pip_mirror": "",
    "sandbox": "sock"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/29 21:44:48 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:44:48 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:44:48 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:44:48 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:44:48 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:44:48 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:44:48 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/29 21:44:48 remove worker.pid",
    "2019/06/29 21:44:48 exiting",
    ""
  ]
}
POP conf: {'registry_cache_ms': 3000, 'sandbox': 'sock', 'registry': '/tmp/tmpsqzdeewf'}
PUSH conf: {'handler_cache_mb': 250, 'import_cache_mb': 250, 'sandbox': 'sock', 'registry': '/root/open-lambda/test-registry'}
========================================
stress_one_lambda
========================================
RUN ./ol worker -p=test-dir --detach
b'using existing OL directory at /root/open-lambda/test-dir\nStarting worker: pid=15582, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 15582\n'
{
  "test": "stress_one_lambda",
  "params": {
    "procs": 1,
    "seconds": 15
  },
  "pass": true,
  "seconds": 15.038692712783813,
  "total_seconds": 15.269287824630737,
  "stats": {
    "reqs_per_sec": 296.8
  },
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 3,
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 4,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 4,
    "Create()/fork-proc:ms.avg": 10,
    "Create()/fork-proc:ms.cnt": 3,
    "Create()/fresh-proc:ms.avg": 151,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 3,
    "Create()/make-root-fs:ms.cnt": 4,
    "Create():ms.avg": 49,
    "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": 4452,
    "Unpause():ms.avg": 0,
    "Unpause():ms.cnt": 4449,
    "fork():ms.avg": 10,
    "fork():ms.cnt": 3,
    "forkRequest:ms.avg": 8,
    "forkRequest:ms.cnt": 3,
    "move-to-cg-after-fork:ms.avg": 1,
    "move-to-cg-after-fork:ms.cnt": 3
  },
  "conf": {
    "worker_port": "5000",
    "import_cache_mb": 250,
    "registry": "/root/open-lambda/test-registry",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "handler_cache_mb": 250,
    "docker_runtime": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "server_mode": "lambda",
    "Skip_pull_existing": false,
    "registry_cache_ms": 5000,
    "startup_pkgs": null,
    "sandbox_config": {},
    "pip_mirror": "",
    "sandbox": "sock"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/29 21:45:03 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:03 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:03 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:03 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:03 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:03 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:04 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/29 21:45:04 remove worker.pid",
    "2019/06/29 21:45:04 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=15629, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 15629\n'
{
  "test": "stress_one_lambda",
  "params": {
    "procs": 2,
    "seconds": 15
  },
  "pass": true,
  "seconds": 15.045116901397705,
  "total_seconds": 15.276396989822388,
  "stats": {
    "reqs_per_sec": 297.2
  },
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 3,
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 4,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 4,
    "Create()/fork-proc:ms.avg": 9,
    "Create()/fork-proc:ms.cnt": 3,
    "Create()/fresh-proc:ms.avg": 155,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 3,
    "Create()/make-root-fs:ms.cnt": 4,
    "Create():ms.avg": 49,
    "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": 3985,
    "Unpause():ms.avg": 0,
    "Unpause():ms.cnt": 3982,
    "fork():ms.avg": 9,
    "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": {
    "worker_port": "5000",
    "import_cache_mb": 250,
    "registry": "/root/open-lambda/test-registry",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "handler_cache_mb": 250,
    "docker_runtime": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "server_mode": "lambda",
    "Skip_pull_existing": false,
    "registry_cache_ms": 5000,
    "startup_pkgs": null,
    "sandbox_config": {},
    "pip_mirror": "",
    "sandbox": "sock"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/29 21:45:19 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:19 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:19 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:19 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:19 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:19 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:19 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/29 21:45:19 remove worker.pid",
    "2019/06/29 21:45:19 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=15677, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 15677\n'
{
  "test": "stress_one_lambda",
  "params": {
    "procs": 8,
    "seconds": 15
  },
  "pass": true,
  "seconds": 15.075851440429688,
  "total_seconds": 15.308581829071045,
  "stats": {
    "reqs_per_sec": 266.8666666666667
  },
  "ol-stats": {
    "Channel():ms.avg": 0,
    "Channel():ms.cnt": 3,
    "Create()/acquire-cgroup:ms.avg": 0,
    "Create()/acquire-cgroup:ms.cnt": 4,
    "Create()/acquire-mem:ms.avg": 0,
    "Create()/acquire-mem:ms.cnt": 4,
    "Create()/fork-proc:ms.avg": 9,
    "Create()/fork-proc:ms.cnt": 3,
    "Create()/fresh-proc:ms.avg": 199,
    "Create()/fresh-proc:ms.cnt": 1,
    "Create()/make-root-fs:ms.avg": 3,
    "Create()/make-root-fs:ms.cnt": 4,
    "Create():ms.avg": 61,
    "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": 3398,
    "Unpause():ms.avg": 0,
    "Unpause():ms.cnt": 3395,
    "fork():ms.avg": 9,
    "fork():ms.cnt": 3,
    "forkRequest:ms.avg": 9,
    "forkRequest:ms.cnt": 3,
    "move-to-cg-after-fork:ms.avg": 0,
    "move-to-cg-after-fork:ms.cnt": 3
  },
  "conf": {
    "worker_port": "5000",
    "import_cache_mb": 100,
    "registry": "/root/open-lambda/test-registry",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "handler_cache_mb": 500,
    "docker_runtime": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "server_mode": "lambda",
    "Skip_pull_existing": false,
    "registry_cache_ms": 5000,
    "startup_pkgs": null,
    "sandbox_config": {},
    "pip_mirror": "",
    "sandbox": "sock"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/29 21:45:34 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:34 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:34 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:34 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:34 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:34 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:34 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/29 21:45:34 remove worker.pid",
    "2019/06/29 21:45:34 exiting",
    ""
  ]
}
POP conf: {'handler_cache_mb': 500, 'import_cache_mb': 100}
POP conf: {'handler_cache_mb': 250, 'import_cache_mb': 250, 'sandbox': 'sock', 'registry': '/root/open-lambda/test-registry'}
PUSH conf: {'handler_cache_mb': 250, 'import_cache_mb': 250, 'sandbox': 'sock'}
PUSH conf: {'registry': '/tmp/tmpgslc4q12'}
========================================
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=15732, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 15732\n'
{
  "test": "call_each_once_exec",
  "params": {
    "alloc_mb": 1,
    "lambda_count": 100
  },
  "pass": true,
  "seconds": 2.523200511932373,
  "total_seconds": 2.7618370056152344,
  "stats": {
    "reqs_per_sec": 39.632594491941106
  },
  "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": 0,
    "Destroy()/kill-procs:ms.cnt": 101,
    "Destroy()/remove-root:ms.avg": 0,
    "Destroy()/remove-root:ms.cnt": 101,
    "Destroy()/remove-scratch:ms.avg": 0,
    "Destroy()/remove-scratch:ms.cnt": 101,
    "Destroy():ms.avg": 0,
    "Destroy():ms.cnt": 197,
    "Pause():ms.avg": 4,
    "Pause():ms.cnt": 100,
    "evict:ms.avg": 0,
    "evict:ms.cnt": 96,
    "fork():ms.avg": 7,
    "fork():ms.cnt": 100,
    "forkRequest:ms.avg": 5,
    "forkRequest:ms.cnt": 100,
    "move-to-cg-after-fork:ms.avg": 1,
    "move-to-cg-after-fork:ms.cnt": 100
  },
  "conf": {
    "worker_port": "5000",
    "import_cache_mb": 250,
    "registry": "/tmp/tmpgslc4q12",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "handler_cache_mb": 250,
    "docker_runtime": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "server_mode": "lambda",
    "Skip_pull_existing": false,
    "registry_cache_ms": 5000,
    "startup_pkgs": null,
    "sandbox_config": {},
    "pip_mirror": "",
    "sandbox": "sock"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/29 21:45:37 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:37 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:37 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:37 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:37 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:37 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:45:37 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/29 21:45:37 remove worker.pid",
    "2019/06/29 21:45:37 exiting",
    ""
  ]
}
POP conf: {'registry': '/tmp/tmpgslc4q12'}
PUSH conf: {'registry': '/tmp/tmp0bvkxhwb'}
========================================
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=16260, port=5000, log=/root/open-lambda/test-dir/worker.out\nready\n'
RUN ./ol kill -p=test-dir
b'Kill worker process with PID 16260\n'
{
  "test": "call_each_once_exec",
  "params": {
    "alloc_mb": 10,
    "lambda_count": 1000
  },
  "pass": true,
  "seconds": 37.17314171791077,
  "total_seconds": 37.50730609893799,
  "stats": {
    "reqs_per_sec": 26.90116367448829
  },
  "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": 175,
    "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": 9,
    "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": {
    "worker_port": "5000",
    "import_cache_mb": 250,
    "registry": "/tmp/tmp0bvkxhwb",
    "sock_cgroups": {
      "max_procs": 10,
      "max_mem_mb": 50
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "handler_cache_mb": 250,
    "docker_runtime": "",
    "cluster_name": "/root/open-lambda/test-dir",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "server_mode": "lambda",
    "Skip_pull_existing": false,
    "registry_cache_ms": 5000,
    "startup_pkgs": null,
    "sandbox_config": {},
    "pip_mirror": "",
    "sandbox": "sock"
  },
  "errors": [],
  "worker_tail": [
    "2019/06/29 21:46:15 remove /sys/fs/cgroup/freezer/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:46:15 remove /sys/fs/cgroup/hugetlb/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:46:15 remove /sys/fs/cgroup/memory/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:46:15 remove /sys/fs/cgroup/perf_event/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:46:15 remove /sys/fs/cgroup/systemd/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:46:15 remove /sys/fs/cgroup/pids/worker-sock-cache [CGROUP POOL worker-sock-cache]",
    "2019/06/29 21:46:15 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2019/06/29 21:46:15 remove worker.pid",
    "2019/06/29 21:46:15 exiting",
    ""
  ]
}
POP conf: {'registry': '/tmp/tmp0bvkxhwb'}
POP conf: {'handler_cache_mb': 250, 'import_cache_mb': 250, 'sandbox': 'sock'}
PASSED: 17, FAILED: 0