python3 -u test.py
RUN ./ol new -p=test-dir
PUSH conf: {'limits': {'installer_mem_mb': 250}}
PUSH conf: {'registry': '/root/open-lambda/test-registry'}
========================================
ping_test
========================================
RUN ./ol worker -p=test-dir --detach
RUN ./ol kill -p=test-dir
{
  "test": "ping_test",
  "params": {},
  "pass": true,
  "conf": {
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "trace": {
      "cgroups": false,
      "evictor": false,
      "memory": false,
      "package": false
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "sock",
    "import_cache_tree": "",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "worker_port": "5000",
    "storage": {
      "root": "private",
      "scratch": "",
      "code": ""
    },
    "pip_mirror": "",
    "features": {
      "downsize_paused_mem": true,
      "import_cache": true,
      "reuse_cgroups": false
    },
    "registry": "/root/open-lambda/test-registry",
    "mem_pool_mb": 500,
    "limits": {
      "procs": 10,
      "mem_mb": 50,
      "swappiness": 0,
      "installer_mem_mb": 250
    },
    "docker_runtime": ""
  },
  "seconds": 2.2508175373077393,
  "total_seconds": 2.520081043243408,
  "stats": {
    "pings_per_sec": 444.2873438870588
  },
  "ol-stats": {
    "fresh-cgroup.cnt": 17,
    "fresh-cgroup.ms-avg": 0
  },
  "errors": [],
  "worker_tail": [
    "2020/09/12 21:45:20 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:20 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:20 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:20 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:20 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:20 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:20 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2020/09/12 21:45:20 remove worker.pid",
    "2020/09/12 21:45:20 exiting",
    ""
  ]
}
PUSH conf: {'features': {'import_cache': False}}
========================================
install_tests
========================================
RUN ./ol worker -p=test-dir --detach
RUN ./ol kill -p=test-dir
{
  "test": "install_tests",
  "params": {},
  "pass": true,
  "conf": {
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "trace": {
      "cgroups": false,
      "evictor": false,
      "memory": false,
      "package": false
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "sock",
    "import_cache_tree": "",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "worker_port": "5000",
    "storage": {
      "root": "private",
      "scratch": "",
      "code": ""
    },
    "pip_mirror": "",
    "features": {
      "downsize_paused_mem": true,
      "import_cache": false,
      "reuse_cgroups": false
    },
    "registry": "/root/open-lambda/test-registry",
    "mem_pool_mb": 500,
    "limits": {
      "procs": 10,
      "mem_mb": 50,
      "swappiness": 0,
      "installer_mem_mb": 250
    },
    "docker_runtime": ""
  },
  "seconds": 11.317379236221313,
  "total_seconds": 11.554328918457031,
  "stats": null,
  "ol-stats": {
    "Channel().cnt": 10,
    "Channel().ms-avg": 0,
    "Create().cnt": 10,
    "Create().ms-avg": 304,
    "Create()/acquire-cgroup.cnt": 10,
    "Create()/acquire-cgroup.ms-avg": 0,
    "Create()/acquire-mem.cnt": 10,
    "Create()/acquire-mem.ms-avg": 0,
    "Create()/fresh-proc.cnt": 10,
    "Create()/fresh-proc.ms-avg": 286,
    "Create()/make-root-fs.cnt": 10,
    "Create()/make-root-fs.ms-avg": 17,
    "Destroy().cnt": 10,
    "Destroy().ms-avg": 2,
    "Destroy()/detach-root.cnt": 10,
    "Destroy()/detach-root.ms-avg": 0,
    "Destroy()/kill-procs.cnt": 10,
    "Destroy()/kill-procs.ms-avg": 1,
    "Destroy()/remove-root.cnt": 10,
    "Destroy()/remove-root.ms-avg": 0,
    "LambdaFunc.Invoke.cnt": 4,
    "LambdaFunc.Invoke.ms-avg": 2819,
    "Pause().cnt": 4,
    "Pause().ms-avg": 3,
    "ServeHTTP.cnt": 4,
    "ServeHTTP.ms-avg": 194,
    "fresh-cgroup.cnt": 27,
    "fresh-cgroup.ms-avg": 0,
    "pull-lambda.cnt": 4,
    "pull-lambda.ms-avg": 13,
    "pull-package.cnt": 6,
    "pull-package.ms-avg": 1330,
    "web-request.cnt": 4,
    "web-request.ms-avg": 2820
  },
  "errors": [],
  "worker_tail": [
    "2020/09/12 21:45:32 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:32 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:32 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:32 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:32 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:32 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:32 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2020/09/12 21:45:32 remove worker.pid",
    "2020/09/12 21:45:32 exiting",
    ""
  ]
}
POP conf: {'features': {'import_cache': False}}
PUSH conf: {'mem_pool_mb': 500}
========================================
install_tests
========================================
RUN ./ol worker -p=test-dir --detach
RUN ./ol kill -p=test-dir
{
  "test": "install_tests",
  "params": {},
  "pass": true,
  "conf": {
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "trace": {
      "cgroups": false,
      "evictor": false,
      "memory": false,
      "package": false
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "docker_runtime": "",
    "sandbox": "sock",
    "import_cache_tree": "",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "worker_port": "5000",
    "storage": {
      "root": "private",
      "scratch": "",
      "code": ""
    },
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-registry",
    "mem_pool_mb": 500,
    "limits": {
      "procs": 10,
      "mem_mb": 50,
      "swappiness": 0,
      "installer_mem_mb": 250
    },
    "features": {
      "downsize_paused_mem": true,
      "import_cache": true,
      "reuse_cgroups": false
    }
  },
  "seconds": 5.7363715171813965,
  "total_seconds": 5.97446870803833,
  "stats": null,
  "ol-stats": {
    "Channel().cnt": 10,
    "Channel().ms-avg": 0,
    "Create().cnt": 11,
    "Create().ms-avg": 94,
    "Create()/acquire-cgroup.cnt": 11,
    "Create()/acquire-cgroup.ms-avg": 0,
    "Create()/acquire-mem.cnt": 11,
    "Create()/acquire-mem.ms-avg": 0,
    "Create()/fork-proc.cnt": 4,
    "Create()/fork-proc.ms-avg": 6,
    "Create()/fresh-proc.cnt": 7,
    "Create()/fresh-proc.ms-avg": 141,
    "Create()/make-root-fs.cnt": 11,
    "Create()/make-root-fs.ms-avg": 1,
    "Destroy().cnt": 11,
    "Destroy().ms-avg": 2,
    "Destroy()/detach-root.cnt": 11,
    "Destroy()/detach-root.ms-avg": 0,
    "Destroy()/kill-procs.cnt": 11,
    "Destroy()/kill-procs.ms-avg": 1,
    "Destroy()/remove-root.cnt": 11,
    "Destroy()/remove-root.ms-avg": 0,
    "LambdaFunc.Invoke.cnt": 4,
    "LambdaFunc.Invoke.ms-avg": 1426,
    "Pause().cnt": 8,
    "Pause().ms-avg": 4,
    "ServeHTTP.cnt": 4,
    "ServeHTTP.ms-avg": 43,
    "Unpause().cnt": 3,
    "Unpause().ms-avg": 0,
    "childExit().cnt": 4,
    "childExit().ms-avg": 0,
    "fork().cnt": 4,
    "fork().ms-avg": 6,
    "forkRequest.cnt": 4,
    "forkRequest.ms-avg": 6,
    "fresh-cgroup.cnt": 28,
    "fresh-cgroup.ms-avg": 0,
    "move-to-cg-after-fork.cnt": 4,
    "move-to-cg-after-fork.ms-avg": 0,
    "pull-lambda.cnt": 4,
    "pull-lambda.ms-avg": 1,
    "pull-package.cnt": 6,
    "pull-package.ms-avg": 886,
    "web-request.cnt": 4,
    "web-request.ms-avg": 1426
  },
  "errors": [],
  "worker_tail": [
    "2020/09/12 21:45:38 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:38 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:38 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:38 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:38 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:38 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:38 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2020/09/12 21:45:38 remove worker.pid",
    "2020/09/12 21:45:38 exiting",
    ""
  ]
}
POP conf: {'mem_pool_mb': 500}
PUSH conf: {'sandbox': 'docker', 'features': {'import_cache': False}}
========================================
install_tests
========================================
RUN ./ol worker -p=test-dir --detach
RUN ./ol kill -p=test-dir
{
  "test": "install_tests",
  "params": {},
  "pass": true,
  "conf": {
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "trace": {
      "cgroups": false,
      "evictor": false,
      "memory": false,
      "package": false
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "docker",
    "import_cache_tree": "",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "worker_port": "5000",
    "storage": {
      "root": "private",
      "scratch": "",
      "code": ""
    },
    "pip_mirror": "",
    "features": {
      "downsize_paused_mem": true,
      "import_cache": false,
      "reuse_cgroups": false
    },
    "registry": "/root/open-lambda/test-registry",
    "mem_pool_mb": 500,
    "limits": {
      "procs": 10,
      "mem_mb": 50,
      "swappiness": 0,
      "installer_mem_mb": 250
    },
    "docker_runtime": ""
  },
  "seconds": 17.232370376586914,
  "total_seconds": 18.25937795639038,
  "stats": null,
  "ol-stats": {
    "Channel().cnt": 10,
    "Channel().ms-avg": 0,
    "Create().cnt": 10,
    "Create().ms-avg": 891,
    "Destroy().cnt": 10,
    "Destroy().ms-avg": 244,
    "LambdaFunc.Invoke.cnt": 4,
    "LambdaFunc.Invoke.ms-avg": 4296,
    "Pause().cnt": 4,
    "Pause().ms-avg": 29,
    "ServeHTTP.cnt": 4,
    "ServeHTTP.ms-avg": 1,
    "pull-lambda.cnt": 4,
    "pull-lambda.ms-avg": 1,
    "pull-package.cnt": 6,
    "pull-package.ms-avg": 1900,
    "web-request.cnt": 4,
    "web-request.ms-avg": 4296
  },
  "errors": [],
  "worker_tail": [
    "2020/09/12 21:45:56 Kill function: install",
    "2020/09/12 21:45:56 Destroy() [SB 6]",
    "2020/09/12 21:45:56 Kill function: install2",
    "2020/09/12 21:45:56 Destroy() [SB 7]",
    "2020/09/12 21:45:56 Kill function: install3",
    "2020/09/12 21:45:56 Destroy() [SB 9]",
    "2020/09/12 21:45:56 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2020/09/12 21:45:56 remove worker.pid",
    "2020/09/12 21:45:56 exiting",
    ""
  ]
}
POP conf: {'sandbox': 'docker', 'features': {'import_cache': False}}
========================================
fork_bomb
========================================
RUN ./ol worker -p=test-dir --detach
RUN ./ol kill -p=test-dir
{
  "test": "fork_bomb",
  "params": {},
  "pass": true,
  "conf": {
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "trace": {
      "cgroups": false,
      "evictor": false,
      "memory": false,
      "package": false
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "docker_runtime": "",
    "sandbox": "sock",
    "import_cache_tree": "",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "worker_port": "5000",
    "storage": {
      "root": "private",
      "scratch": "",
      "code": ""
    },
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-registry",
    "mem_pool_mb": 500,
    "limits": {
      "procs": 10,
      "mem_mb": 50,
      "swappiness": 0,
      "installer_mem_mb": 250
    },
    "features": {
      "downsize_paused_mem": true,
      "import_cache": true,
      "reuse_cgroups": false
    }
  },
  "seconds": 0.19942903518676758,
  "total_seconds": 0.4322638511657715,
  "stats": null,
  "ol-stats": {
    "Channel().cnt": 1,
    "Channel().ms-avg": 0,
    "Create().cnt": 2,
    "Create().ms-avg": 78,
    "Create()/acquire-cgroup.cnt": 2,
    "Create()/acquire-cgroup.ms-avg": 0,
    "Create()/acquire-mem.cnt": 2,
    "Create()/acquire-mem.ms-avg": 0,
    "Create()/fork-proc.cnt": 1,
    "Create()/fork-proc.ms-avg": 6,
    "Create()/fresh-proc.cnt": 1,
    "Create()/fresh-proc.ms-avg": 141,
    "Create()/make-root-fs.cnt": 2,
    "Create()/make-root-fs.ms-avg": 4,
    "Destroy().cnt": 2,
    "Destroy().ms-avg": 2,
    "Destroy()/detach-root.cnt": 2,
    "Destroy()/detach-root.ms-avg": 1,
    "Destroy()/kill-procs.cnt": 2,
    "Destroy()/kill-procs.ms-avg": 0,
    "Destroy()/remove-root.cnt": 2,
    "Destroy()/remove-root.ms-avg": 0,
    "LambdaFunc.Invoke.cnt": 1,
    "LambdaFunc.Invoke.ms-avg": 195,
    "Pause().cnt": 2,
    "Pause().ms-avg": 4,
    "ServeHTTP.cnt": 1,
    "ServeHTTP.ms-avg": 11,
    "childExit().cnt": 1,
    "childExit().ms-avg": 0,
    "fork().cnt": 1,
    "fork().ms-avg": 6,
    "forkRequest.cnt": 1,
    "forkRequest.ms-avg": 6,
    "fresh-cgroup.cnt": 19,
    "fresh-cgroup.ms-avg": 0,
    "move-to-cg-after-fork.cnt": 1,
    "move-to-cg-after-fork.ms-avg": 0,
    "pull-lambda.cnt": 1,
    "pull-lambda.ms-avg": 21,
    "web-request.cnt": 1,
    "web-request.ms-avg": 195
  },
  "errors": [],
  "worker_tail": [
    "2020/09/12 21:45:57 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:57 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:57 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:57 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:57 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:57 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:57 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2020/09/12 21:45:57 remove worker.pid",
    "2020/09/12 21:45:57 exiting",
    ""
  ]
}
========================================
max_mem_alloc
========================================
RUN ./ol worker -p=test-dir --detach
RUN ./ol kill -p=test-dir
{
  "test": "max_mem_alloc",
  "params": {},
  "pass": true,
  "conf": {
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "trace": {
      "cgroups": false,
      "evictor": false,
      "memory": false,
      "package": false
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "docker_runtime": "",
    "sandbox": "sock",
    "import_cache_tree": "",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "worker_port": "5000",
    "storage": {
      "root": "private",
      "scratch": "",
      "code": ""
    },
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-registry",
    "mem_pool_mb": 500,
    "limits": {
      "procs": 10,
      "mem_mb": 50,
      "swappiness": 0,
      "installer_mem_mb": 250
    },
    "features": {
      "downsize_paused_mem": true,
      "import_cache": true,
      "reuse_cgroups": false
    }
  },
  "seconds": 0.8417501449584961,
  "total_seconds": 1.0734970569610596,
  "stats": null,
  "ol-stats": {
    "Channel().cnt": 1,
    "Channel().ms-avg": 0,
    "Create().cnt": 2,
    "Create().ms-avg": 75,
    "Create()/acquire-cgroup.cnt": 2,
    "Create()/acquire-cgroup.ms-avg": 0,
    "Create()/acquire-mem.cnt": 2,
    "Create()/acquire-mem.ms-avg": 0,
    "Create()/fork-proc.cnt": 1,
    "Create()/fork-proc.ms-avg": 6,
    "Create()/fresh-proc.cnt": 1,
    "Create()/fresh-proc.ms-avg": 137,
    "Create()/make-root-fs.cnt": 2,
    "Create()/make-root-fs.ms-avg": 4,
    "Destroy().cnt": 2,
    "Destroy().ms-avg": 2,
    "Destroy()/detach-root.cnt": 2,
    "Destroy()/detach-root.ms-avg": 1,
    "Destroy()/kill-procs.cnt": 2,
    "Destroy()/kill-procs.ms-avg": 0,
    "Destroy()/remove-root.cnt": 2,
    "Destroy()/remove-root.ms-avg": 0,
    "LambdaFunc.Invoke.cnt": 1,
    "LambdaFunc.Invoke.ms-avg": 838,
    "Pause().cnt": 2,
    "Pause().ms-avg": 4,
    "ServeHTTP.cnt": 1,
    "ServeHTTP.ms-avg": 677,
    "childExit().cnt": 1,
    "childExit().ms-avg": 0,
    "fork().cnt": 1,
    "fork().ms-avg": 6,
    "forkRequest.cnt": 1,
    "forkRequest.ms-avg": 6,
    "fresh-cgroup.cnt": 19,
    "fresh-cgroup.ms-avg": 0,
    "move-to-cg-after-fork.cnt": 1,
    "move-to-cg-after-fork.ms-avg": 0,
    "pull-lambda.cnt": 1,
    "pull-lambda.ms-avg": 2,
    "web-request.cnt": 1,
    "web-request.ms-avg": 838
  },
  "errors": [],
  "worker_tail": [
    "2020/09/12 21:45:58 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:58 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:58 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:58 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:58 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:58 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:58 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2020/09/12 21:45:58 remove worker.pid",
    "2020/09/12 21:45:58 exiting",
    ""
  ]
}
PUSH conf: {'mem_pool_mb': 500}
========================================
numpy_test
========================================
RUN ./ol worker -p=test-dir --detach
RUN ./ol kill -p=test-dir
{
  "test": "numpy_test",
  "params": {},
  "pass": false,
  "conf": {
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "trace": {
      "cgroups": false,
      "evictor": false,
      "memory": false,
      "package": false
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "docker_runtime": "",
    "sandbox": "sock",
    "import_cache_tree": "",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "worker_port": "5000",
    "storage": {
      "root": "private",
      "scratch": "",
      "code": ""
    },
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-registry",
    "mem_pool_mb": 500,
    "limits": {
      "procs": 10,
      "mem_mb": 50,
      "swappiness": 0,
      "installer_mem_mb": 250
    },
    "features": {
      "downsize_paused_mem": true,
      "import_cache": true,
      "reuse_cgroups": false
    }
  },
  "seconds": null,
  "total_seconds": 47.49580144882202,
  "stats": null,
  "ol-stats": {
    "Channel().cnt": 10,
    "Channel().ms-avg": 0,
    "Create().cnt": 11,
    "Create().ms-avg": 166,
    "Create()/acquire-cgroup.cnt": 11,
    "Create()/acquire-cgroup.ms-avg": 0,
    "Create()/acquire-mem.cnt": 11,
    "Create()/acquire-mem.ms-avg": 0,
    "Create()/fork-proc.cnt": 4,
    "Create()/fork-proc.ms-avg": 6,
    "Create()/fresh-proc.cnt": 7,
    "Create()/fresh-proc.ms-avg": 254,
    "Create()/make-root-fs.cnt": 11,
    "Create()/make-root-fs.ms-avg": 1,
    "Destroy().cnt": 11,
    "Destroy().ms-avg": 2,
    "Destroy()/detach-root.cnt": 11,
    "Destroy()/detach-root.ms-avg": 0,
    "Destroy()/kill-procs.cnt": 11,
    "Destroy()/kill-procs.ms-avg": 1,
    "Destroy()/remove-root.cnt": 11,
    "Destroy()/remove-root.ms-avg": 0,
    "LambdaFunc.Invoke.cnt": 4,
    "LambdaFunc.Invoke.ms-avg": 11800,
    "Pause().cnt": 8,
    "Pause().ms-avg": 3,
    "ServeHTTP.cnt": 4,
    "ServeHTTP.ms-avg": 134,
    "Unpause().cnt": 3,
    "Unpause().ms-avg": 0,
    "childExit().cnt": 4,
    "childExit().ms-avg": 0,
    "fork().cnt": 4,
    "fork().ms-avg": 6,
    "forkRequest.cnt": 4,
    "forkRequest.ms-avg": 6,
    "fresh-cgroup.cnt": 28,
    "fresh-cgroup.ms-avg": 0,
    "move-to-cg-after-fork.cnt": 4,
    "move-to-cg-after-fork.ms-avg": 0,
    "pull-lambda.cnt": 4,
    "pull-lambda.ms-avg": 4,
    "pull-package.cnt": 6,
    "pull-package.ms-avg": 7740,
    "web-request.cnt": 4,
    "web-request.ms-avg": 11800
  },
  "errors": [
    [
      "Traceback (most recent call last):",
      "  File \"test.py\", line 81, in wrapper",
      "    rv = fn(**kwargs)",
      "  File \"test.py\", line 246, in numpy_test",
      "    raise Exception(\"STATUS %d: %s\" % (r.status_code, r.text))",
      "Exception: STATUS 500: <html><title>500: Internal Server Error</title><body>500: Internal Server Error</body></html>",
      ""
    ]
  ],
  "worker_tail": [
    "using existing OL directory at /root/open-lambda/test-dir",
    "2020/09/12 21:45:58 save PID 10323 to file /root/open-lambda/test-dir/worker/worker.pid",
    "2020/09/12 21:45:58 Start Lambda Server",
    "2020/09/12 21:45:58 Storage dir at /root/open-lambda/test-dir/worker/code",
    "2020/09/12 21:45:58 Storage dir at /root/open-lambda/test-dir/worker/scratch",
    "2020/09/12 21:45:58 Create SandboxPool",
    "2020/09/12 21:45:58 create /sys/fs/cgroup/blkio/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:58 create /sys/fs/cgroup/cpu/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:58 create /sys/fs/cgroup/devices/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:58 create /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:58 create /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:58 create /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:58 create /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:58 create /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:58 create /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:58 Storage dir at /root/open-lambda/test-dir/worker/root-sandboxes",
    "2020/09/12 21:45:58 Create DepTracer",
    "2020/09/12 21:45:58 Create PackagePuller",
    "2020/09/12 21:45:58 Create ImportCache",
    "2020/09/12 21:45:58 Import Cache Tree:",
    "2020/09/12 21:45:58 0    - ROOT",
    "2020/09/12 21:45:58 Create HandlerPuller",
    "2020/09/12 21:45:58 Setups Handlers",
    "2020/09/12 21:45:58 Execute handler by POSTing to localhost:5000/run/<lambda>",
    "2020/09/12 21:45:58 Get status by sending request to localhost:5000/status",
    "2020/09/12 21:45:58 start creating/serving CGs [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:45:58 Receive request to /pid",
    "2020/09/12 21:45:58 Receive request to /run/numpy15",
    "2020/09/12 21:45:58 debug: LambdaFunc.Task() runs on goroutine 18 [FUNC numpy15]",
    "2020/09/12 21:45:58 do pip install, using scratchDir='/root/open-lambda/test-dir/lambda/packages/numpy==1.15'",
    "2020/09/12 21:45:58 run pip install numpy==1.15 from a new Sandbox to /root/open-lambda/test-dir/lambda/packages/numpy==1.15 on host",
    "2020/09/12 21:45:58 <sandboxes>.Create(<nil>, true, /root/open-lambda/test-dir/worker/admin-lambdas/pip-install, /root/open-lambda/test-dir/lambda/packages/numpy==1.15, <installs=[], imports=[], mem-limit-mb=250>)=1... [SOCK POOL sandboxes]",
    "2020/09/12 21:45:58 use cgroup cg-1 [SOCK 1]",
    "sock2.py: started new process with args: sock2.py /host/bootstrap.py 9",
    "sock2.py: joined cgroup, close FD 3",
    "sock2.py: joined cgroup, close FD 4",
    "sock2.py: joined cgroup, close FD 5",
    "sock2.py: joined cgroup, close FD 6",
    "sock2.py: joined cgroup, close FD 7",
    "sock2.py: joined cgroup, close FD 8",
    "sock2.py: joined cgroup, close FD 9",
    "sock2.py: joined cgroup, close FD 10",
    "sock2.py: joined cgroup, close FD 11",
    "sock2.py: start web server on fd: 3",
    "2020/09/12 21:45:58 ...returns <SB 1>, <nil> [SOCK POOL sandboxes]",
    "2020/09/12 21:45:58 Channel() [SB 1]",
    "Collecting numpy==1.15",
    "  Downloading numpy-1.15.0-cp36-cp36m-manylinux1_x86_64.whl (13.9 MB)",
    "Installing collected packages: numpy",
    "Successfully installed numpy-1.15.0",
    "pip install returned code 0",
    "2020/09/12 21:46:06 Destroy() [SB 1]",
    "2020/09/12 21:46:06 CG ref count decremented to 0 [SOCK 1]",
    "2020/09/12 21:46:06 waiting for 1 procs in cg-1 to die [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:06 killed PIDs [10336] in CG [SOCK 1]",
    "2020/09/12 21:46:06 unmount and remove dirs [SOCK 1]",
    "2020/09/12 21:46:06 increase instances to 1 [FUNC numpy15]",
    "2020/09/12 21:46:06 Try using Zygote from <ROOT>",
    "2020/09/12 21:46:06 <sandboxes>.Create(<nil>, false, /root/open-lambda/test-dir/worker/code/1004-import-cache, /root/open-lambda/test-dir/worker/scratch/1005-import-cache, <installs=[], imports=[], mem-limit-mb=50>)=2... [SOCK POOL sandboxes]",
    "2020/09/12 21:46:06 use cgroup cg-2 [SOCK 2]",
    "sock2.py: started new process with args: sock2.py /host/bootstrap.py 9",
    "sock2.py: joined cgroup, close FD 3",
    "sock2.py: joined cgroup, close FD 4",
    "sock2.py: joined cgroup, close FD 5",
    "sock2.py: joined cgroup, close FD 6",
    "sock2.py: joined cgroup, close FD 7",
    "sock2.py: joined cgroup, close FD 8",
    "sock2.py: joined cgroup, close FD 9",
    "sock2.py: joined cgroup, close FD 10",
    "sock2.py: joined cgroup, close FD 11",
    "sock2.py: start fork server on fd: 3",
    "2020/09/12 21:46:06 ...returns <SB 2>, <nil> [SOCK POOL sandboxes]",
    "2020/09/12 21:46:06 <sandboxes>.Create(<SB 2>, true, /root/open-lambda/test-dir/worker/code/1001-numpy15, /root/open-lambda/test-dir/worker/scratch/1003-numpy15, <installs=[numpy==1.15], imports=[], mem-limit-mb=50>)=3... [SOCK POOL sandboxes]",
    "2020/09/12 21:46:06 use cgroup cg-3 [SOCK 3]",
    "2020/09/12 21:46:06 fork(SB 3) [SB 2]",
    "sock2.py: start web server on fd: 3",
    "2020/09/12 21:46:06 move PID 10351 from CG cg-2 to CG cg-3 [SOCK 2]",
    "2020/09/12 21:46:06 ...returns <SB 3>, <nil> [SOCK POOL sandboxes]",
    "2020/09/12 21:46:06 Pause() [SB 2]",
    "2020/09/12 21:46:06 Channel() [SB 3]",
    "2020/09/12 21:46:07 Pause() [SB 3]",
    "2020/09/12 21:46:07 Receive request to /run/numpy16",
    "2020/09/12 21:46:07 debug: LambdaFunc.Task() runs on goroutine 32 [FUNC numpy16]",
    "2020/09/12 21:46:07 do pip install, using scratchDir='/root/open-lambda/test-dir/lambda/packages/numpy==1.16'",
    "2020/09/12 21:46:07 run pip install numpy==1.16 from a new Sandbox to /root/open-lambda/test-dir/lambda/packages/numpy==1.16 on host",
    "2020/09/12 21:46:07 <sandboxes>.Create(<nil>, true, /root/open-lambda/test-dir/worker/admin-lambdas/pip-install, /root/open-lambda/test-dir/lambda/packages/numpy==1.16, <installs=[], imports=[], mem-limit-mb=250>)=4... [SOCK POOL sandboxes]",
    "2020/09/12 21:46:07 use cgroup cg-4 [SOCK 4]",
    "sock2.py: started new process with args: sock2.py /host/bootstrap.py 9",
    "sock2.py: joined cgroup, close FD 3",
    "sock2.py: joined cgroup, close FD 4",
    "sock2.py: joined cgroup, close FD 5",
    "sock2.py: joined cgroup, close FD 6",
    "sock2.py: joined cgroup, close FD 7",
    "sock2.py: joined cgroup, close FD 8",
    "sock2.py: joined cgroup, close FD 9",
    "sock2.py: joined cgroup, close FD 10",
    "sock2.py: joined cgroup, close FD 11",
    "sock2.py: start web server on fd: 3",
    "2020/09/12 21:46:07 ...returns <SB 4>, <nil> [SOCK POOL sandboxes]",
    "2020/09/12 21:46:07 Channel() [SB 4]",
    "Collecting numpy==1.16",
    "  Downloading numpy-1.16.0-cp36-cp36m-manylinux1_x86_64.whl (17.3 MB)",
    "Installing collected packages: numpy",
    "Successfully installed numpy-1.16.0",
    "pip install returned code 0",
    "2020/09/12 21:46:33 Destroy() [SB 4]",
    "2020/09/12 21:46:33 CG ref count decremented to 0 [SOCK 4]",
    "2020/09/12 21:46:33 waiting for 1 procs in cg-4 to die [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:33 killed PIDs [10357] in CG [SOCK 4]",
    "2020/09/12 21:46:33 unmount and remove dirs [SOCK 4]",
    "2020/09/12 21:46:33 increase instances to 1 [FUNC numpy16]",
    "2020/09/12 21:46:33 Try using Zygote from <ROOT>",
    "2020/09/12 21:46:33 Unpause() [SB 2]",
    "2020/09/12 21:46:33 <sandboxes>.Create(<SB 2>, true, /root/open-lambda/test-dir/worker/code/1008-numpy16, /root/open-lambda/test-dir/worker/scratch/1010-numpy16, <installs=[numpy==1.16], imports=[], mem-limit-mb=50>)=5... [SOCK POOL sandboxes]",
    "2020/09/12 21:46:33 use cgroup cg-5 [SOCK 5]",
    "2020/09/12 21:46:33 fork(SB 5) [SB 2]",
    "2020/09/12 21:46:33 move PID 10369 from CG cg-2 to CG cg-5 [SOCK 2]",
    "2020/09/12 21:46:33 ...returns <SB 5>, <nil> [SOCK POOL sandboxes]",
    "2020/09/12 21:46:33 Pause() [SB 2]",
    "sock2.py: start web server on fd: 3",
    "2020/09/12 21:46:33 Channel() [SB 5]",
    "2020/09/12 21:46:33 Pause() [SB 5]",
    "2020/09/12 21:46:33 Receive request to /run/numpy15",
    "2020/09/12 21:46:33 increase instances to 1 [FUNC numpy15]",
    "2020/09/12 21:46:33 Try using Zygote from <ROOT>",
    "2020/09/12 21:46:33 Unpause() [SB 2]",
    "2020/09/12 21:46:33 <sandboxes>.Create(<SB 2>, true, /root/open-lambda/test-dir/worker/code/1012-numpy15, /root/open-lambda/test-dir/worker/scratch/1013-numpy15, <installs=[numpy==1.15], imports=[], mem-limit-mb=50>)=6... [SOCK POOL sandboxes]",
    "2020/09/12 21:46:33 use cgroup cg-6 [SOCK 6]",
    "2020/09/12 21:46:33 fork(SB 6) [SB 2]",
    "sock2.py: start web server on fd: 3",
    "2020/09/12 21:46:33 Destroy() [SB 3]",
    "2020/09/12 21:46:33 CG ref count decremented to 0 [SOCK 3]",
    "2020/09/12 21:46:33 waiting for 2 procs in cg-3 to die [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:33 move PID 10374 from CG cg-2 to CG cg-6 [SOCK 2]",
    "2020/09/12 21:46:33 ...returns <SB 6>, <nil> [SOCK POOL sandboxes]",
    "2020/09/12 21:46:33 Pause() [SB 2]",
    "2020/09/12 21:46:33 killed PIDs [10351 10353] in CG [SOCK 3]",
    "2020/09/12 21:46:33 unmount and remove dirs [SOCK 3]",
    "2020/09/12 21:46:33 childExit(SB 3) [SB 2]",
    "2020/09/12 21:46:33 Channel() [SB 6]",
    "2020/09/12 21:46:33 CG ref count decremented to 3 [SOCK 2]",
    "2020/09/12 21:46:33 Pause() [SB 6]",
    "2020/09/12 21:46:33 Receive request to /run/pandas15",
    "2020/09/12 21:46:33 debug: LambdaFunc.Task() runs on goroutine 53 [FUNC pandas15]",
    "2020/09/12 21:46:33 do pip install, using scratchDir='/root/open-lambda/test-dir/lambda/packages/pandas'",
    "2020/09/12 21:46:33 run pip install pandas from a new Sandbox to /root/open-lambda/test-dir/lambda/packages/pandas on host",
    "2020/09/12 21:46:33 <sandboxes>.Create(<nil>, true, /root/open-lambda/test-dir/worker/admin-lambdas/pip-install, /root/open-lambda/test-dir/lambda/packages/pandas, <installs=[], imports=[], mem-limit-mb=250>)=7... [SOCK POOL sandboxes]",
    "2020/09/12 21:46:33 use cgroup cg-7 [SOCK 7]",
    "sock2.py: started new process with args: sock2.py /host/bootstrap.py 9",
    "sock2.py: joined cgroup, close FD 3",
    "sock2.py: joined cgroup, close FD 4",
    "sock2.py: joined cgroup, close FD 5",
    "sock2.py: joined cgroup, close FD 6",
    "sock2.py: joined cgroup, close FD 7",
    "sock2.py: joined cgroup, close FD 8",
    "sock2.py: joined cgroup, close FD 9",
    "sock2.py: joined cgroup, close FD 10",
    "sock2.py: joined cgroup, close FD 11",
    "sock2.py: start web server on fd: 3",
    "2020/09/12 21:46:34 ...returns <SB 7>, <nil> [SOCK POOL sandboxes]",
    "2020/09/12 21:46:34 Channel() [SB 7]",
    "Collecting pandas",
    "  Downloading pandas-1.1.2-cp36-cp36m-manylinux1_x86_64.whl (10.5 MB)",
    "Installing collected packages: pandas",
    "Successfully installed pandas-1.1.2",
    "pip install returned code 0",
    "2020/09/12 21:46:42 Destroy() [SB 7]",
    "2020/09/12 21:46:42 CG ref count decremented to 0 [SOCK 7]",
    "2020/09/12 21:46:42 waiting for 1 procs in cg-7 to die [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:42 killed PIDs [10382] in CG [SOCK 7]",
    "2020/09/12 21:46:42 unmount and remove dirs [SOCK 7]",
    "2020/09/12 21:46:42 do pip install, using scratchDir='/root/open-lambda/test-dir/lambda/packages/pytz'",
    "2020/09/12 21:46:42 run pip install pytz from a new Sandbox to /root/open-lambda/test-dir/lambda/packages/pytz on host",
    "2020/09/12 21:46:42 <sandboxes>.Create(<nil>, true, /root/open-lambda/test-dir/worker/admin-lambdas/pip-install, /root/open-lambda/test-dir/lambda/packages/pytz, <installs=[], imports=[], mem-limit-mb=250>)=8... [SOCK POOL sandboxes]",
    "2020/09/12 21:46:42 use cgroup cg-8 [SOCK 8]",
    "sock2.py: started new process with args: sock2.py /host/bootstrap.py 9",
    "sock2.py: joined cgroup, close FD 3",
    "sock2.py: joined cgroup, close FD 4",
    "sock2.py: joined cgroup, close FD 5",
    "sock2.py: joined cgroup, close FD 6",
    "sock2.py: joined cgroup, close FD 7",
    "sock2.py: joined cgroup, close FD 8",
    "sock2.py: joined cgroup, close FD 9",
    "sock2.py: joined cgroup, close FD 10",
    "sock2.py: joined cgroup, close FD 11",
    "sock2.py: start web server on fd: 3",
    "2020/09/12 21:46:42 ...returns <SB 8>, <nil> [SOCK POOL sandboxes]",
    "2020/09/12 21:46:42 Channel() [SB 8]",
    "Collecting pytz",
    "  Downloading pytz-2020.1-py2.py3-none-any.whl (510 kB)",
    "Installing collected packages: pytz",
    "Successfully installed pytz-2020.1",
    "pip install returned code 0",
    "2020/09/12 21:46:43 Destroy() [SB 8]",
    "2020/09/12 21:46:43 CG ref count decremented to 0 [SOCK 8]",
    "2020/09/12 21:46:43 waiting for 1 procs in cg-8 to die [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:43 killed PIDs [10395] in CG [SOCK 8]",
    "2020/09/12 21:46:43 unmount and remove dirs [SOCK 8]",
    "2020/09/12 21:46:43 do pip install, using scratchDir='/root/open-lambda/test-dir/lambda/packages/python-dateutil'",
    "2020/09/12 21:46:43 run pip install python-dateutil from a new Sandbox to /root/open-lambda/test-dir/lambda/packages/python-dateutil on host",
    "2020/09/12 21:46:43 <sandboxes>.Create(<nil>, true, /root/open-lambda/test-dir/worker/admin-lambdas/pip-install, /root/open-lambda/test-dir/lambda/packages/python-dateutil, <installs=[], imports=[], mem-limit-mb=250>)=9... [SOCK POOL sandboxes]",
    "2020/09/12 21:46:43 use cgroup cg-9 [SOCK 9]",
    "sock2.py: started new process with args: sock2.py /host/bootstrap.py 9",
    "sock2.py: joined cgroup, close FD 3",
    "sock2.py: joined cgroup, close FD 4",
    "sock2.py: joined cgroup, close FD 5",
    "sock2.py: joined cgroup, close FD 6",
    "sock2.py: joined cgroup, close FD 7",
    "sock2.py: joined cgroup, close FD 8",
    "sock2.py: joined cgroup, close FD 9",
    "sock2.py: joined cgroup, close FD 10",
    "sock2.py: joined cgroup, close FD 11",
    "sock2.py: start web server on fd: 3",
    "2020/09/12 21:46:43 ...returns <SB 9>, <nil> [SOCK POOL sandboxes]",
    "2020/09/12 21:46:43 Channel() [SB 9]",
    "Collecting python-dateutil",
    "  Downloading python_dateutil-2.8.1-py2.py3-none-any.whl (227 kB)",
    "Installing collected packages: python-dateutil",
    "Successfully installed python-dateutil-2.8.1",
    "pip install returned code 0",
    "2020/09/12 21:46:44 Destroy() [SB 9]",
    "2020/09/12 21:46:44 CG ref count decremented to 0 [SOCK 9]",
    "2020/09/12 21:46:44 waiting for 1 procs in cg-9 to die [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:44 killed PIDs [10408] in CG [SOCK 9]",
    "2020/09/12 21:46:44 unmount and remove dirs [SOCK 9]",
    "2020/09/12 21:46:44 do pip install, using scratchDir='/root/open-lambda/test-dir/lambda/packages/six'",
    "2020/09/12 21:46:44 run pip install six from a new Sandbox to /root/open-lambda/test-dir/lambda/packages/six on host",
    "2020/09/12 21:46:44 <sandboxes>.Create(<nil>, true, /root/open-lambda/test-dir/worker/admin-lambdas/pip-install, /root/open-lambda/test-dir/lambda/packages/six, <installs=[], imports=[], mem-limit-mb=250>)=10... [SOCK POOL sandboxes]",
    "2020/09/12 21:46:44 use cgroup cg-10 [SOCK 10]",
    "sock2.py: started new process with args: sock2.py /host/bootstrap.py 9",
    "sock2.py: joined cgroup, close FD 3",
    "sock2.py: joined cgroup, close FD 4",
    "sock2.py: joined cgroup, close FD 5",
    "sock2.py: joined cgroup, close FD 6",
    "sock2.py: joined cgroup, close FD 7",
    "sock2.py: joined cgroup, close FD 8",
    "sock2.py: joined cgroup, close FD 9",
    "sock2.py: joined cgroup, close FD 10",
    "sock2.py: joined cgroup, close FD 11",
    "sock2.py: start web server on fd: 3",
    "2020/09/12 21:46:44 ...returns <SB 10>, <nil> [SOCK POOL sandboxes]",
    "2020/09/12 21:46:44 Channel() [SB 10]",
    "Collecting six",
    "  Downloading six-1.15.0-py2.py3-none-any.whl (10 kB)",
    "Installing collected packages: six",
    "Successfully installed six-1.15.0",
    "pip install returned code 0",
    "2020/09/12 21:46:45 Destroy() [SB 10]",
    "2020/09/12 21:46:45 CG ref count decremented to 0 [SOCK 10]",
    "2020/09/12 21:46:45 waiting for 1 procs in cg-10 to die [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:45 killed PIDs [10421] in CG [SOCK 10]",
    "2020/09/12 21:46:45 unmount and remove dirs [SOCK 10]",
    "2020/09/12 21:46:45 increase instances to 1 [FUNC pandas15]",
    "2020/09/12 21:46:45 Try using Zygote from <ROOT>",
    "2020/09/12 21:46:45 Unpause() [SB 2]",
    "2020/09/12 21:46:45 <sandboxes>.Create(<SB 2>, true, /root/open-lambda/test-dir/worker/code/1015-pandas15, /root/open-lambda/test-dir/worker/scratch/1020-pandas15, <installs=[numpy==1.15,pandas,pytz,python-dateutil,six], imports=[], mem-limit-mb=50>)=11... [SOCK POOL sandboxes]",
    "2020/09/12 21:46:45 use cgroup cg-11 [SOCK 11]",
    "2020/09/12 21:46:45 fork(SB 11) [SB 2]",
    "2020/09/12 21:46:45 move PID 10433 from CG cg-2 to CG cg-11 [SOCK 2]",
    "2020/09/12 21:46:45 ...returns <SB 11>, <nil> [SOCK POOL sandboxes]",
    "2020/09/12 21:46:45 Pause() [SB 2]",
    "sock2.py: start web server on fd: 3",
    "2020/09/12 21:46:45 Channel() [SB 11]",
    "ERROR:tornado.application:Uncaught exception POST /run/pandas15 (0.0.0.0)",
    "HTTPServerRequest(protocol='http', host='localhost:5000', method='POST', uri='/run/pandas15', version='HTTP/1.1', remote_ip='0.0.0.0', headers={'Host': 'localhost:5000', 'User-Agent': 'python-requests/2.9.1', 'Content-Length': '22', 'Accept': '*/*', 'Accept-Encoding': 'gzip, deflate', 'X-Forwarded-For': '127.0.0.1'})",
    "Traceback (most recent call last):",
    "  File \"/usr/local/lib/python3.6/dist-packages/tornado/web.py\", line 1510, in _execute",
    "    result = method(*self.path_args, **self.path_kwargs)",
    "  File \"sock2.py\", line 32, in post",
    "    import f",
    "  File \"/handler/f.py\", line 3, in <module>",
    "    import pandas",
    "  File \"/packages/pandas/files/pandas/__init__.py\", line 22, in <module>",
    "    from pandas.compat.numpy import (",
    "  File \"/packages/pandas/files/pandas/compat/numpy/__init__.py\", line 21, in <module>",
    "    \"this version of pandas is incompatible with numpy < 1.15.4\\n\"",
    "ImportError: this version of pandas is incompatible with numpy < 1.15.4",
    "your numpy version is 1.15.0.",
    "Please upgrade numpy to >= 1.15.4 to use this pandas version",
    "2020/09/12 21:46:45 Pause() [SB 11]",
    "2020/09/12 21:46:45 received kill signal, cleaning up",
    "2020/09/12 21:46:45 Kill function: numpy15",
    "2020/09/12 21:46:45 Destroy() [SB 6]",
    "2020/09/12 21:46:45 CG ref count decremented to 0 [SOCK 6]",
    "2020/09/12 21:46:45 killed PIDs [10374 10378] in CG [SOCK 6]",
    "2020/09/12 21:46:45 unmount and remove dirs [SOCK 6]",
    "2020/09/12 21:46:45 childExit(SB 6) [SB 2]",
    "2020/09/12 21:46:45 CG ref count decremented to 3 [SOCK 2]",
    "2020/09/12 21:46:45 Kill function: numpy16",
    "2020/09/12 21:46:45 Destroy() [SB 5]",
    "2020/09/12 21:46:45 CG ref count decremented to 0 [SOCK 5]",
    "2020/09/12 21:46:45 killed PIDs [10369 10371] in CG [SOCK 5]",
    "2020/09/12 21:46:45 unmount and remove dirs [SOCK 5]",
    "2020/09/12 21:46:45 childExit(SB 5) [SB 2]",
    "2020/09/12 21:46:45 CG ref count decremented to 2 [SOCK 2]",
    "2020/09/12 21:46:45 Kill function: pandas15",
    "2020/09/12 21:46:45 Destroy() [SB 11]",
    "2020/09/12 21:46:45 CG ref count decremented to 0 [SOCK 11]",
    "2020/09/12 21:46:45 killed PIDs [10433 10435] in CG [SOCK 11]",
    "2020/09/12 21:46:45 unmount and remove dirs [SOCK 11]",
    "2020/09/12 21:46:45 childExit(SB 11) [SB 2]",
    "2020/09/12 21:46:45 CG ref count decremented to 1 [SOCK 2]",
    "2020/09/12 21:46:45 Import Cache Tree:",
    "2020/09/12 21:46:45 4    - ROOT",
    "2020/09/12 21:46:45 Destroy() [SB 2]",
    "2020/09/12 21:46:45 CG ref count decremented to 0 [SOCK 2]",
    "2020/09/12 21:46:45 killed PIDs [10349] in CG [SOCK 2]",
    "2020/09/12 21:46:45 unmount and remove dirs [SOCK 2]",
    "2020/09/12 21:46:45 make sure all memory is free [SOCK POOL sandboxes]",
    "2020/09/12 21:46:45 memory pool emptied [SOCK POOL sandboxes]",
    "2020/09/12 21:46:45 received shutdown request [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:45 empty queues and release CGs [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:45 remove /sys/fs/cgroup/blkio/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:45 remove /sys/fs/cgroup/cpu/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:45 remove /sys/fs/cgroup/devices/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:45 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:45 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:45 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:45 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:45 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:45 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:45 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2020/09/12 21:46:45 remove worker.pid",
    "2020/09/12 21:46:45 exiting",
    ""
  ]
}
POP conf: {'mem_pool_mb': 500}
POP conf: {'registry': '/root/open-lambda/test-registry'}
PUSH conf: {'server_mode': 'sock', 'mem_pool_mb': 500}
========================================
sock_churn {'procs': 1, 'seconds': 5, 'baseline': 0, 'fork': False}
========================================
RUN ./ol worker -p=test-dir --detach
RUN ./ol kill -p=test-dir
{
  "test": "sock_churn",
  "params": {
    "procs": 1,
    "seconds": 5,
    "baseline": 0,
    "fork": false
  },
  "pass": true,
  "conf": {
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "server_mode": "sock",
    "registry_cache_ms": 5000,
    "trace": {
      "cgroups": false,
      "evictor": false,
      "memory": false,
      "package": false
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "docker_runtime": "",
    "sandbox": "sock",
    "import_cache_tree": "",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "worker_port": "5000",
    "storage": {
      "root": "private",
      "scratch": "",
      "code": ""
    },
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-dir/registry",
    "mem_pool_mb": 500,
    "limits": {
      "procs": 10,
      "mem_mb": 50,
      "swappiness": 0,
      "installer_mem_mb": 250
    },
    "features": {
      "downsize_paused_mem": true,
      "import_cache": true,
      "reuse_cgroups": false
    }
  },
  "seconds": 5.190110921859741,
  "total_seconds": 5.411984920501709,
  "stats": {
    "sandboxes_per_sec": 6.2
  },
  "ol-stats": {
    "Create().cnt": 31,
    "Create().ms-avg": 146,
    "Create()/acquire-cgroup.cnt": 31,
    "Create()/acquire-cgroup.ms-avg": 0,
    "Create()/acquire-mem.cnt": 31,
    "Create()/acquire-mem.ms-avg": 0,
    "Create()/fresh-proc.cnt": 31,
    "Create()/fresh-proc.ms-avg": 139,
    "Create()/make-root-fs.cnt": 31,
    "Create()/make-root-fs.ms-avg": 6,
    "Destroy().cnt": 62,
    "Destroy().ms-avg": 1,
    "Destroy()/detach-root.cnt": 31,
    "Destroy()/detach-root.ms-avg": 1,
    "Destroy()/kill-procs.cnt": 31,
    "Destroy()/kill-procs.ms-avg": 0,
    "Destroy()/remove-root.cnt": 31,
    "Destroy()/remove-root.ms-avg": 0,
    "fresh-cgroup.cnt": 48,
    "fresh-cgroup.ms-avg": 0
  },
  "errors": [],
  "worker_tail": [
    "2020/09/12 21:46:51 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:51 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:51 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:51 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:51 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:51 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:46:51 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2020/09/12 21:46:51 remove worker.pid",
    "2020/09/12 21:46:51 exiting",
    ""
  ]
}
========================================
sock_churn {'procs': 1, 'seconds': 10, 'baseline': 0, 'fork': True}
========================================
RUN ./ol worker -p=test-dir --detach
RUN ./ol kill -p=test-dir
{
  "test": "sock_churn",
  "params": {
    "procs": 1,
    "seconds": 10,
    "baseline": 0,
    "fork": true
  },
  "pass": true,
  "conf": {
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "server_mode": "sock",
    "registry_cache_ms": 5000,
    "trace": {
      "cgroups": false,
      "evictor": false,
      "memory": false,
      "package": false
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "docker_runtime": "",
    "sandbox": "sock",
    "import_cache_tree": "",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "worker_port": "5000",
    "storage": {
      "root": "private",
      "scratch": "",
      "code": ""
    },
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-dir/registry",
    "mem_pool_mb": 500,
    "limits": {
      "procs": 10,
      "mem_mb": 50,
      "swappiness": 0,
      "installer_mem_mb": 250
    },
    "features": {
      "downsize_paused_mem": true,
      "import_cache": true,
      "reuse_cgroups": false
    }
  },
  "seconds": 10.182212829589844,
  "total_seconds": 10.416188716888428,
  "stats": {
    "sandboxes_per_sec": 44.6
  },
  "ol-stats": {
    "Create().cnt": 447,
    "Create().ms-avg": 10,
    "Create()/acquire-cgroup.cnt": 447,
    "Create()/acquire-cgroup.ms-avg": 0,
    "Create()/acquire-mem.cnt": 447,
    "Create()/acquire-mem.ms-avg": 0,
    "Create()/fork-proc.cnt": 446,
    "Create()/fork-proc.ms-avg": 6,
    "Create()/fresh-proc.cnt": 1,
    "Create()/fresh-proc.ms-avg": 138,
    "Create()/make-root-fs.cnt": 447,
    "Create()/make-root-fs.ms-avg": 3,
    "Destroy().cnt": 893,
    "Destroy().ms-avg": 1,
    "Destroy()/detach-root.cnt": 447,
    "Destroy()/detach-root.ms-avg": 1,
    "Destroy()/kill-procs.cnt": 447,
    "Destroy()/kill-procs.ms-avg": 0,
    "Destroy()/remove-root.cnt": 447,
    "Destroy()/remove-root.ms-avg": 0,
    "childExit().cnt": 446,
    "childExit().ms-avg": 0,
    "fork().cnt": 446,
    "fork().ms-avg": 6,
    "forkRequest.cnt": 446,
    "forkRequest.ms-avg": 5,
    "fresh-cgroup.cnt": 464,
    "fresh-cgroup.ms-avg": 0,
    "move-to-cg-after-fork.cnt": 446,
    "move-to-cg-after-fork.ms-avg": 0
  },
  "errors": [],
  "worker_tail": [
    "2020/09/12 21:47:01 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:01 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:01 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:01 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:01 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:01 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:01 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2020/09/12 21:47:01 remove worker.pid",
    "2020/09/12 21:47:01 exiting",
    ""
  ]
}
========================================
sock_churn {'procs': 15, 'seconds': 10, 'baseline': 0, 'fork': True}
========================================
RUN ./ol worker -p=test-dir --detach
RUN ./ol kill -p=test-dir
{
  "test": "sock_churn",
  "params": {
    "procs": 15,
    "seconds": 10,
    "baseline": 0,
    "fork": true
  },
  "pass": true,
  "conf": {
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "server_mode": "sock",
    "registry_cache_ms": 5000,
    "trace": {
      "cgroups": false,
      "evictor": false,
      "memory": false,
      "package": false
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "docker_runtime": "",
    "sandbox": "sock",
    "import_cache_tree": "",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "worker_port": "5000",
    "storage": {
      "root": "private",
      "scratch": "",
      "code": ""
    },
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-dir/registry",
    "mem_pool_mb": 500,
    "limits": {
      "procs": 10,
      "mem_mb": 50,
      "swappiness": 0,
      "installer_mem_mb": 250
    },
    "features": {
      "downsize_paused_mem": true,
      "import_cache": true,
      "reuse_cgroups": false
    }
  },
  "seconds": 10.353375673294067,
  "total_seconds": 10.599755764007568,
  "stats": {
    "sandboxes_per_sec": 46.4
  },
  "ol-stats": {
    "Create().cnt": 465,
    "Create().ms-avg": 83,
    "Create()/acquire-cgroup.cnt": 465,
    "Create()/acquire-cgroup.ms-avg": 0,
    "Create()/acquire-mem.cnt": 465,
    "Create()/acquire-mem.ms-avg": 16,
    "Create()/fork-proc.cnt": 464,
    "Create()/fork-proc.ms-avg": 63,
    "Create()/fresh-proc.cnt": 1,
    "Create()/fresh-proc.ms-avg": 133,
    "Create()/make-root-fs.cnt": 465,
    "Create()/make-root-fs.ms-avg": 2,
    "Destroy().cnt": 929,
    "Destroy().ms-avg": 47,
    "Destroy()/detach-root.cnt": 465,
    "Destroy()/detach-root.ms-avg": 1,
    "Destroy()/kill-procs.cnt": 465,
    "Destroy()/kill-procs.ms-avg": 11,
    "Destroy()/remove-root.cnt": 465,
    "Destroy()/remove-root.ms-avg": 0,
    "childExit().cnt": 464,
    "childExit().ms-avg": 59,
    "fork().cnt": 464,
    "fork().ms-avg": 63,
    "forkRequest.cnt": 464,
    "forkRequest.ms-avg": 10,
    "fresh-cgroup.cnt": 482,
    "fresh-cgroup.ms-avg": 3,
    "move-to-cg-after-fork.cnt": 464,
    "move-to-cg-after-fork.ms-avg": 2
  },
  "errors": [],
  "worker_tail": [
    "2020/09/12 21:47:12 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:12 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:12 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:12 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:12 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:12 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:12 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2020/09/12 21:47:12 remove worker.pid",
    "2020/09/12 21:47:12 exiting",
    ""
  ]
}
========================================
sock_churn {'procs': 1, 'seconds': 10, 'baseline': 32, 'fork': True}
========================================
RUN ./ol worker -p=test-dir --detach
RUN ./ol kill -p=test-dir
{
  "test": "sock_churn",
  "params": {
    "procs": 1,
    "seconds": 10,
    "baseline": 32,
    "fork": true
  },
  "pass": true,
  "conf": {
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "server_mode": "sock",
    "registry_cache_ms": 5000,
    "trace": {
      "cgroups": false,
      "evictor": false,
      "memory": false,
      "package": false
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "docker_runtime": "",
    "sandbox": "sock",
    "import_cache_tree": "",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "worker_port": "5000",
    "storage": {
      "root": "private",
      "scratch": "",
      "code": ""
    },
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-dir/registry",
    "mem_pool_mb": 500,
    "limits": {
      "procs": 10,
      "mem_mb": 50,
      "swappiness": 0,
      "installer_mem_mb": 250
    },
    "features": {
      "downsize_paused_mem": true,
      "import_cache": true,
      "reuse_cgroups": false
    }
  },
  "seconds": 10.772578716278076,
  "total_seconds": 11.151399612426758,
  "stats": {
    "sandboxes_per_sec": 40.9
  },
  "ol-stats": {
    "Create().cnt": 442,
    "Create().ms-avg": 7,
    "Create()/acquire-cgroup.cnt": 442,
    "Create()/acquire-cgroup.ms-avg": 0,
    "Create()/acquire-mem.cnt": 442,
    "Create()/acquire-mem.ms-avg": 0,
    "Create()/fork-proc.cnt": 441,
    "Create()/fork-proc.ms-avg": 6,
    "Create()/fresh-proc.cnt": 1,
    "Create()/fresh-proc.ms-avg": 133,
    "Create()/make-root-fs.cnt": 442,
    "Create()/make-root-fs.ms-avg": 0,
    "Destroy().cnt": 851,
    "Destroy().ms-avg": 0,
    "Destroy()/detach-root.cnt": 442,
    "Destroy()/detach-root.ms-avg": 0,
    "Destroy()/kill-procs.cnt": 442,
    "Destroy()/kill-procs.ms-avg": 0,
    "Destroy()/remove-root.cnt": 442,
    "Destroy()/remove-root.ms-avg": 0,
    "Pause().cnt": 32,
    "Pause().ms-avg": 0,
    "childExit().cnt": 441,
    "childExit().ms-avg": 0,
    "fork().cnt": 441,
    "fork().ms-avg": 6,
    "forkRequest.cnt": 441,
    "forkRequest.ms-avg": 5,
    "fresh-cgroup.cnt": 459,
    "fresh-cgroup.ms-avg": 0,
    "move-to-cg-after-fork.cnt": 441,
    "move-to-cg-after-fork.ms-avg": 0
  },
  "errors": [],
  "worker_tail": [
    "2020/09/12 21:47:23 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:23 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:23 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:23 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:23 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:23 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:23 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2020/09/12 21:47:23 remove worker.pid",
    "2020/09/12 21:47:23 exiting",
    ""
  ]
}
========================================
sock_churn {'procs': 15, 'seconds': 10, 'baseline': 32, 'fork': True}
========================================
RUN ./ol worker -p=test-dir --detach
RUN ./ol kill -p=test-dir
{
  "test": "sock_churn",
  "params": {
    "procs": 15,
    "seconds": 10,
    "baseline": 32,
    "fork": true
  },
  "pass": true,
  "conf": {
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "server_mode": "sock",
    "registry_cache_ms": 5000,
    "trace": {
      "cgroups": false,
      "evictor": false,
      "memory": false,
      "package": false
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "docker_runtime": "",
    "sandbox": "sock",
    "import_cache_tree": "",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "worker_port": "5000",
    "storage": {
      "root": "private",
      "scratch": "",
      "code": ""
    },
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-dir/registry",
    "mem_pool_mb": 500,
    "limits": {
      "procs": 10,
      "mem_mb": 50,
      "swappiness": 0,
      "installer_mem_mb": 250
    },
    "features": {
      "downsize_paused_mem": true,
      "import_cache": true,
      "reuse_cgroups": false
    }
  },
  "seconds": 10.94928503036499,
  "total_seconds": 11.303258657455444,
  "stats": {
    "sandboxes_per_sec": 40.8
  },
  "ol-stats": {
    "Create().cnt": 441,
    "Create().ms-avg": 117,
    "Create()/acquire-cgroup.cnt": 441,
    "Create()/acquire-cgroup.ms-avg": 0,
    "Create()/acquire-mem.cnt": 441,
    "Create()/acquire-mem.ms-avg": 76,
    "Create()/fork-proc.cnt": 440,
    "Create()/fork-proc.ms-avg": 39,
    "Create()/fresh-proc.cnt": 1,
    "Create()/fresh-proc.ms-avg": 144,
    "Create()/make-root-fs.cnt": 441,
    "Create()/make-root-fs.ms-avg": 0,
    "Destroy().cnt": 849,
    "Destroy().ms-avg": 49,
    "Destroy()/detach-root.cnt": 441,
    "Destroy()/detach-root.ms-avg": 0,
    "Destroy()/kill-procs.cnt": 441,
    "Destroy()/kill-procs.ms-avg": 10,
    "Destroy()/remove-root.cnt": 441,
    "Destroy()/remove-root.ms-avg": 0,
    "Pause().cnt": 32,
    "Pause().ms-avg": 0,
    "childExit().cnt": 440,
    "childExit().ms-avg": 45,
    "fork().cnt": 440,
    "fork().ms-avg": 39,
    "forkRequest.cnt": 440,
    "forkRequest.ms-avg": 8,
    "fresh-cgroup.cnt": 458,
    "fresh-cgroup.ms-avg": 2,
    "move-to-cg-after-fork.cnt": 440,
    "move-to-cg-after-fork.ms-avg": 2
  },
  "errors": [],
  "worker_tail": [
    "2020/09/12 21:47:34 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:34 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:34 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:34 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:34 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:34 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:34 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2020/09/12 21:47:34 remove worker.pid",
    "2020/09/12 21:47:34 exiting",
    ""
  ]
}
POP conf: {'server_mode': 'sock', 'mem_pool_mb': 500}
PUSH conf: {'registry': '/tmp/tmp8zuqa_ij', 'registry_cache_ms': 3000}
========================================
update_code
========================================
RUN ./ol worker -p=test-dir --detach
RUN ./ol kill -p=test-dir
{
  "test": "update_code",
  "params": {},
  "pass": false,
  "conf": {
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "server_mode": "lambda",
    "registry_cache_ms": 3000,
    "trace": {
      "cgroups": false,
      "evictor": false,
      "memory": false,
      "package": false
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "sock",
    "import_cache_tree": "",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "worker_port": "5000",
    "storage": {
      "root": "private",
      "scratch": "",
      "code": ""
    },
    "pip_mirror": "",
    "features": {
      "downsize_paused_mem": true,
      "import_cache": true,
      "reuse_cgroups": false
    },
    "registry": "/tmp/tmp8zuqa_ij",
    "mem_pool_mb": 500,
    "limits": {
      "procs": 10,
      "mem_mb": 50,
      "swappiness": 0,
      "installer_mem_mb": 250
    },
    "docker_runtime": ""
  },
  "seconds": null,
  "total_seconds": 3.269627809524536,
  "stats": null,
  "ol-stats": {
    "Channel().cnt": 2,
    "Channel().ms-avg": 0,
    "Create().cnt": 3,
    "Create().ms-avg": 519,
    "Create()/acquire-cgroup.cnt": 3,
    "Create()/acquire-cgroup.ms-avg": 0,
    "Create()/acquire-mem.cnt": 3,
    "Create()/acquire-mem.ms-avg": 0,
    "Create()/fork-proc.cnt": 2,
    "Create()/fork-proc.ms-avg": 6,
    "Create()/fresh-proc.cnt": 1,
    "Create()/fresh-proc.ms-avg": 1531,
    "Create()/make-root-fs.cnt": 3,
    "Create()/make-root-fs.ms-avg": 3,
    "Destroy().cnt": 3,
    "Destroy().ms-avg": 3,
    "Destroy()/detach-root.cnt": 3,
    "Destroy()/detach-root.ms-avg": 0,
    "Destroy()/kill-procs.cnt": 3,
    "Destroy()/kill-procs.ms-avg": 1,
    "Destroy()/remove-root.cnt": 3,
    "Destroy()/remove-root.ms-avg": 0,
    "LambdaFunc.Invoke.cnt": 413,
    "LambdaFunc.Invoke.ms-avg": 4,
    "Pause().cnt": 415,
    "Pause().ms-avg": 0,
    "ServeHTTP.cnt": 413,
    "ServeHTTP.ms-avg": 0,
    "Unpause().cnt": 412,
    "Unpause().ms-avg": 0,
    "childExit().cnt": 2,
    "childExit().ms-avg": 0,
    "fork().cnt": 2,
    "fork().ms-avg": 6,
    "forkRequest.cnt": 2,
    "forkRequest.ms-avg": 4,
    "fresh-cgroup.cnt": 20,
    "fresh-cgroup.ms-avg": 0,
    "move-to-cg-after-fork.cnt": 2,
    "move-to-cg-after-fork.ms-avg": 1,
    "pull-lambda.cnt": 2,
    "pull-lambda.ms-avg": 1,
    "web-request.cnt": 413,
    "web-request.ms-avg": 4
  },
  "errors": [
    [
      "Traceback (most recent call last):",
      "  File \"test.py\", line 81, in wrapper",
      "    rv = fn(**kwargs)",
      "  File \"test.py\", line 406, in update_code",
      "    assert(t1 - t0 >= cache_seconds - 1)",
      "AssertionError",
      ""
    ]
  ],
  "worker_tail": [
    "using existing OL directory at /root/open-lambda/test-dir",
    "2020/09/12 21:47:34 save PID 17928 to file /root/open-lambda/test-dir/worker/worker.pid",
    "2020/09/12 21:47:34 Start Lambda Server",
    "2020/09/12 21:47:34 Storage dir at /root/open-lambda/test-dir/worker/code",
    "2020/09/12 21:47:34 Storage dir at /root/open-lambda/test-dir/worker/scratch",
    "2020/09/12 21:47:34 Create SandboxPool",
    "2020/09/12 21:47:34 create /sys/fs/cgroup/blkio/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:34 create /sys/fs/cgroup/cpu/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:34 create /sys/fs/cgroup/devices/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:34 create /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:34 create /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:34 create /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:34 create /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:34 create /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:34 create /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:34 Storage dir at /root/open-lambda/test-dir/worker/root-sandboxes",
    "2020/09/12 21:47:34 Create DepTracer",
    "2020/09/12 21:47:34 Create PackagePuller",
    "2020/09/12 21:47:34 Create ImportCache",
    "2020/09/12 21:47:34 Import Cache Tree:",
    "2020/09/12 21:47:34 0    - ROOT",
    "2020/09/12 21:47:34 Create HandlerPuller",
    "2020/09/12 21:47:34 Setups Handlers",
    "2020/09/12 21:47:34 Execute handler by POSTing to localhost:5000/run/<lambda>",
    "2020/09/12 21:47:34 Get status by sending request to localhost:5000/status",
    "2020/09/12 21:47:34 start creating/serving CGs [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:34 Receive request to /pid",
    "2020/09/12 21:47:34 Receive request to /run/version",
    "2020/09/12 21:47:34 debug: LambdaFunc.Task() runs on goroutine 19 [FUNC version]",
    "2020/09/12 21:47:34 increase instances to 1 [FUNC version]",
    "2020/09/12 21:47:34 Try using Zygote from <ROOT>",
    "2020/09/12 21:47:34 <sandboxes>.Create(<nil>, false, /root/open-lambda/test-dir/worker/code/1003-import-cache, /root/open-lambda/test-dir/worker/scratch/1004-import-cache, <installs=[], imports=[], mem-limit-mb=50>)=1... [SOCK POOL sandboxes]",
    "2020/09/12 21:47:34 use cgroup cg-1 [SOCK 1]",
    "sock2.py: started new process with args: sock2.py /host/bootstrap.py 9",
    "sock2.py: joined cgroup, close FD 3",
    "sock2.py: joined cgroup, close FD 4",
    "sock2.py: joined cgroup, close FD 5",
    "sock2.py: joined cgroup, close FD 6",
    "sock2.py: joined cgroup, close FD 7",
    "sock2.py: joined cgroup, close FD 8",
    "sock2.py: joined cgroup, close FD 9",
    "sock2.py: joined cgroup, close FD 10",
    "sock2.py: joined cgroup, close FD 11",
    "sock2.py: start fork server on fd: 3",
    "2020/09/12 21:47:36 ...returns <SB 1>, <nil> [SOCK POOL sandboxes]",
    "2020/09/12 21:47:36 <sandboxes>.Create(<SB 1>, true, /root/open-lambda/test-dir/worker/code/1001-version, /root/open-lambda/test-dir/worker/scratch/1002-version, <installs=[], imports=[], mem-limit-mb=50>)=2... [SOCK POOL sandboxes]",
    "2020/09/12 21:47:36 use cgroup cg-2 [SOCK 2]",
    "2020/09/12 21:47:36 fork(SB 2) [SB 1]",
    "sock2.py: start web server on fd: 3",
    "2020/09/12 21:47:36 move PID 17944 from CG cg-1 to CG cg-2 [SOCK 1]",
    "2020/09/12 21:47:36 ...returns <SB 2>, <nil> [SOCK POOL sandboxes]",
    "2020/09/12 21:47:36 Pause() [SB 1]",
    "2020/09/12 21:47:36 Channel() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:36 Receive request to /run/version",
    "2020/09/12 21:47:36 Unpause() [SB 2]",
    "2020/09/12 21:47:36 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 Unpause() [SB 2]",
    "2020/09/12 21:47:37 Pause() [SB 2]",
    "2020/09/12 21:47:37 Receive request to /run/version",
    "2020/09/12 21:47:37 increase instances to 1 [FUNC version]",
    "2020/09/12 21:47:37 Try using Zygote from <ROOT>",
    "2020/09/12 21:47:37 Unpause() [SB 1]",
    "2020/09/12 21:47:37 <sandboxes>.Create(<SB 1>, true, /root/open-lambda/test-dir/worker/code/1007-version, /root/open-lambda/test-dir/worker/scratch/1008-version, <installs=[], imports=[], mem-limit-mb=50>)=3... [SOCK POOL sandboxes]",
    "2020/09/12 21:47:37 use cgroup cg-3 [SOCK 3]",
    "2020/09/12 21:47:37 fork(SB 3) [SB 1]",
    "sock2.py: start web server on fd: 3",
    "2020/09/12 21:47:37 Destroy() [SB 2]",
    "2020/09/12 21:47:37 CG ref count decremented to 0 [SOCK 2]",
    "2020/09/12 21:47:37 waiting for 1 procs in cg-2 to die [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:37 move PID 17948 from CG cg-1 to CG cg-3 [SOCK 1]",
    "2020/09/12 21:47:37 ...returns <SB 3>, <nil> [SOCK POOL sandboxes]",
    "2020/09/12 21:47:37 Pause() [SB 1]",
    "2020/09/12 21:47:37 Channel() [SB 3]",
    "2020/09/12 21:47:37 killed PIDs [17944] in CG [SOCK 2]",
    "2020/09/12 21:47:37 unmount and remove dirs [SOCK 2]",
    "2020/09/12 21:47:37 childExit(SB 2) [SB 1]",
    "2020/09/12 21:47:37 CG ref count decremented to 2 [SOCK 1]",
    "2020/09/12 21:47:37 Pause() [SB 3]",
    "2020/09/12 21:47:37 received kill signal, cleaning up",
    "2020/09/12 21:47:37 Kill function: version",
    "2020/09/12 21:47:37 Destroy() [SB 3]",
    "2020/09/12 21:47:37 CG ref count decremented to 0 [SOCK 3]",
    "2020/09/12 21:47:37 killed PIDs [17948] in CG [SOCK 3]",
    "2020/09/12 21:47:37 unmount and remove dirs [SOCK 3]",
    "2020/09/12 21:47:37 childExit(SB 3) [SB 1]",
    "2020/09/12 21:47:37 CG ref count decremented to 1 [SOCK 1]",
    "2020/09/12 21:47:37 Import Cache Tree:",
    "2020/09/12 21:47:37 2    - ROOT",
    "2020/09/12 21:47:37 Destroy() [SB 1]",
    "2020/09/12 21:47:37 CG ref count decremented to 0 [SOCK 1]",
    "2020/09/12 21:47:37 killed PIDs [17942] in CG [SOCK 1]",
    "2020/09/12 21:47:37 unmount and remove dirs [SOCK 1]",
    "2020/09/12 21:47:37 make sure all memory is free [SOCK POOL sandboxes]",
    "2020/09/12 21:47:37 memory pool emptied [SOCK POOL sandboxes]",
    "2020/09/12 21:47:37 received shutdown request [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:37 empty queues and release CGs [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:37 remove /sys/fs/cgroup/blkio/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:37 remove /sys/fs/cgroup/cpu/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:37 remove /sys/fs/cgroup/devices/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:37 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:37 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:37 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:37 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:37 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:37 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:37 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2020/09/12 21:47:37 remove worker.pid",
    "2020/09/12 21:47:37 exiting",
    ""
  ]
}
POP conf: {'registry': '/tmp/tmp8zuqa_ij', 'registry_cache_ms': 3000}
PUSH conf: {'registry': '/root/open-lambda/test-registry'}
========================================
stress_one_lambda {'procs': 1, 'seconds': 15}
========================================
RUN ./ol worker -p=test-dir --detach
RUN ./ol kill -p=test-dir
{
  "test": "stress_one_lambda",
  "params": {
    "procs": 1,
    "seconds": 15
  },
  "pass": true,
  "conf": {
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "trace": {
      "cgroups": false,
      "evictor": false,
      "memory": false,
      "package": false
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "docker_runtime": "",
    "sandbox": "sock",
    "import_cache_tree": "",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "worker_port": "5000",
    "storage": {
      "root": "private",
      "scratch": "",
      "code": ""
    },
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-registry",
    "mem_pool_mb": 500,
    "limits": {
      "procs": 10,
      "mem_mb": 50,
      "swappiness": 0,
      "installer_mem_mb": 250
    },
    "features": {
      "downsize_paused_mem": true,
      "import_cache": true,
      "reuse_cgroups": false
    }
  },
  "seconds": 15.041430950164795,
  "total_seconds": 15.27382779121399,
  "stats": {
    "reqs_per_sec": 283.8
  },
  "ol-stats": {
    "Channel().cnt": 3,
    "Channel().ms-avg": 0,
    "Create().cnt": 4,
    "Create().ms-avg": 43,
    "Create()/acquire-cgroup.cnt": 4,
    "Create()/acquire-cgroup.ms-avg": 0,
    "Create()/acquire-mem.cnt": 4,
    "Create()/acquire-mem.ms-avg": 0,
    "Create()/fork-proc.cnt": 3,
    "Create()/fork-proc.ms-avg": 7,
    "Create()/fresh-proc.cnt": 1,
    "Create()/fresh-proc.ms-avg": 136,
    "Create()/make-root-fs.cnt": 4,
    "Create()/make-root-fs.ms-avg": 3,
    "Destroy().cnt": 4,
    "Destroy().ms-avg": 3,
    "Destroy()/detach-root.cnt": 4,
    "Destroy()/detach-root.ms-avg": 0,
    "Destroy()/kill-procs.cnt": 4,
    "Destroy()/kill-procs.ms-avg": 2,
    "Destroy()/remove-root.cnt": 4,
    "Destroy()/remove-root.ms-avg": 0,
    "LambdaFunc.Invoke.cnt": 4257,
    "LambdaFunc.Invoke.ms-avg": 1,
    "Pause().cnt": 4260,
    "Pause().ms-avg": 0,
    "ServeHTTP.cnt": 4257,
    "ServeHTTP.ms-avg": 0,
    "Unpause().cnt": 4256,
    "Unpause().ms-avg": 0,
    "childExit().cnt": 3,
    "childExit().ms-avg": 0,
    "fork().cnt": 3,
    "fork().ms-avg": 7,
    "forkRequest.cnt": 3,
    "forkRequest.ms-avg": 6,
    "fresh-cgroup.cnt": 21,
    "fresh-cgroup.ms-avg": 0,
    "move-to-cg-after-fork.cnt": 3,
    "move-to-cg-after-fork.ms-avg": 1,
    "pull-lambda.cnt": 3,
    "pull-lambda.ms-avg": 1,
    "web-request.cnt": 4257,
    "web-request.ms-avg": 1
  },
  "errors": [],
  "worker_tail": [
    "2020/09/12 21:47:53 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:53 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:53 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:53 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:53 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:53 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:47:53 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2020/09/12 21:47:53 remove worker.pid",
    "2020/09/12 21:47:53 exiting",
    ""
  ]
}
========================================
stress_one_lambda {'procs': 2, 'seconds': 15}
========================================
RUN ./ol worker -p=test-dir --detach
RUN ./ol kill -p=test-dir
{
  "test": "stress_one_lambda",
  "params": {
    "procs": 2,
    "seconds": 15
  },
  "pass": true,
  "conf": {
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "trace": {
      "cgroups": false,
      "evictor": false,
      "memory": false,
      "package": false
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "docker_runtime": "",
    "sandbox": "sock",
    "import_cache_tree": "",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "worker_port": "5000",
    "storage": {
      "root": "private",
      "scratch": "",
      "code": ""
    },
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-registry",
    "mem_pool_mb": 500,
    "limits": {
      "procs": 10,
      "mem_mb": 50,
      "swappiness": 0,
      "installer_mem_mb": 250
    },
    "features": {
      "downsize_paused_mem": true,
      "import_cache": true,
      "reuse_cgroups": false
    }
  },
  "seconds": 15.048140048980713,
  "total_seconds": 15.279416561126709,
  "stats": {
    "reqs_per_sec": 285.1333333333333
  },
  "ol-stats": {
    "Channel().cnt": 3,
    "Channel().ms-avg": 0,
    "Create().cnt": 4,
    "Create().ms-avg": 43,
    "Create()/acquire-cgroup.cnt": 4,
    "Create()/acquire-cgroup.ms-avg": 0,
    "Create()/acquire-mem.cnt": 4,
    "Create()/acquire-mem.ms-avg": 0,
    "Create()/fork-proc.cnt": 3,
    "Create()/fork-proc.ms-avg": 7,
    "Create()/fresh-proc.cnt": 1,
    "Create()/fresh-proc.ms-avg": 134,
    "Create()/make-root-fs.cnt": 4,
    "Create()/make-root-fs.ms-avg": 3,
    "Destroy().cnt": 4,
    "Destroy().ms-avg": 2,
    "Destroy()/detach-root.cnt": 4,
    "Destroy()/detach-root.ms-avg": 0,
    "Destroy()/kill-procs.cnt": 4,
    "Destroy()/kill-procs.ms-avg": 1,
    "Destroy()/remove-root.cnt": 4,
    "Destroy()/remove-root.ms-avg": 0,
    "LambdaFunc.Invoke.cnt": 4277,
    "LambdaFunc.Invoke.ms-avg": 1,
    "Pause().cnt": 3850,
    "Pause().ms-avg": 0,
    "ServeHTTP.cnt": 4277,
    "ServeHTTP.ms-avg": 0,
    "Unpause().cnt": 3846,
    "Unpause().ms-avg": 0,
    "childExit().cnt": 3,
    "childExit().ms-avg": 0,
    "fork().cnt": 3,
    "fork().ms-avg": 7,
    "forkRequest.cnt": 3,
    "forkRequest.ms-avg": 6,
    "fresh-cgroup.cnt": 21,
    "fresh-cgroup.ms-avg": 0,
    "move-to-cg-after-fork.cnt": 3,
    "move-to-cg-after-fork.ms-avg": 0,
    "pull-lambda.cnt": 3,
    "pull-lambda.ms-avg": 1,
    "web-request.cnt": 4277,
    "web-request.ms-avg": 1
  },
  "errors": [],
  "worker_tail": [
    "2020/09/12 21:48:08 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:48:08 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:48:08 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:48:08 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:48:08 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:48:08 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:48:08 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2020/09/12 21:48:08 remove worker.pid",
    "2020/09/12 21:48:08 exiting",
    ""
  ]
}
========================================
stress_one_lambda {'procs': 8, 'seconds': 15}
========================================
RUN ./ol worker -p=test-dir --detach
RUN ./ol kill -p=test-dir
{
  "test": "stress_one_lambda",
  "params": {
    "procs": 8,
    "seconds": 15
  },
  "pass": true,
  "conf": {
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "trace": {
      "cgroups": false,
      "evictor": false,
      "memory": false,
      "package": false
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "docker_runtime": "",
    "sandbox": "sock",
    "import_cache_tree": "",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "worker_port": "5000",
    "storage": {
      "root": "private",
      "scratch": "",
      "code": ""
    },
    "pip_mirror": "",
    "registry": "/root/open-lambda/test-registry",
    "mem_pool_mb": 500,
    "limits": {
      "procs": 10,
      "mem_mb": 50,
      "swappiness": 0,
      "installer_mem_mb": 250
    },
    "features": {
      "downsize_paused_mem": true,
      "import_cache": true,
      "reuse_cgroups": false
    }
  },
  "seconds": 15.062458753585815,
  "total_seconds": 15.29331660270691,
  "stats": {
    "reqs_per_sec": 257.93333333333334
  },
  "ol-stats": {
    "Channel().cnt": 3,
    "Channel().ms-avg": 0,
    "Create().cnt": 4,
    "Create().ms-avg": 43,
    "Create()/acquire-cgroup.cnt": 4,
    "Create()/acquire-cgroup.ms-avg": 0,
    "Create()/acquire-mem.cnt": 4,
    "Create()/acquire-mem.ms-avg": 0,
    "Create()/fork-proc.cnt": 3,
    "Create()/fork-proc.ms-avg": 7,
    "Create()/fresh-proc.cnt": 1,
    "Create()/fresh-proc.ms-avg": 134,
    "Create()/make-root-fs.cnt": 4,
    "Create()/make-root-fs.ms-avg": 3,
    "Destroy().cnt": 4,
    "Destroy().ms-avg": 3,
    "Destroy()/detach-root.cnt": 4,
    "Destroy()/detach-root.ms-avg": 0,
    "Destroy()/kill-procs.cnt": 4,
    "Destroy()/kill-procs.ms-avg": 1,
    "Destroy()/remove-root.cnt": 4,
    "Destroy()/remove-root.ms-avg": 0,
    "LambdaFunc.Invoke.cnt": 3869,
    "LambdaFunc.Invoke.ms-avg": 2,
    "Pause().cnt": 2905,
    "Pause().ms-avg": 0,
    "ServeHTTP.cnt": 3869,
    "ServeHTTP.ms-avg": 0,
    "Unpause().cnt": 2901,
    "Unpause().ms-avg": 0,
    "childExit().cnt": 3,
    "childExit().ms-avg": 0,
    "fork().cnt": 3,
    "fork().ms-avg": 7,
    "forkRequest.cnt": 3,
    "forkRequest.ms-avg": 6,
    "fresh-cgroup.cnt": 21,
    "fresh-cgroup.ms-avg": 0,
    "move-to-cg-after-fork.cnt": 3,
    "move-to-cg-after-fork.ms-avg": 0,
    "pull-lambda.cnt": 3,
    "pull-lambda.ms-avg": 5,
    "web-request.cnt": 3869,
    "web-request.ms-avg": 3
  },
  "errors": [],
  "worker_tail": [
    "2020/09/12 21:48:23 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:48:23 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:48:23 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:48:23 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:48:23 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:48:23 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:48:23 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2020/09/12 21:48:23 remove worker.pid",
    "2020/09/12 21:48:23 exiting",
    ""
  ]
}
POP conf: {'registry': '/root/open-lambda/test-registry'}
PUSH conf: {'features': {'reuse_cgroups': True}}
PUSH conf: {'registry': '/tmp/tmpzua_s1lo'}
========================================
call_each_once_exec {'lambda_count': 100, 'alloc_mb': 1}
========================================
RUN ./ol worker -p=test-dir --detach
RUN ./ol kill -p=test-dir
{
  "test": "call_each_once_exec",
  "params": {
    "lambda_count": 100,
    "alloc_mb": 1
  },
  "pass": true,
  "conf": {
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "trace": {
      "cgroups": false,
      "evictor": false,
      "memory": false,
      "package": false
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "sandbox": "sock",
    "import_cache_tree": "",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "worker_port": "5000",
    "storage": {
      "root": "private",
      "scratch": "",
      "code": ""
    },
    "pip_mirror": "",
    "features": {
      "downsize_paused_mem": true,
      "import_cache": true,
      "reuse_cgroups": true
    },
    "registry": "/tmp/tmpzua_s1lo",
    "mem_pool_mb": 500,
    "limits": {
      "procs": 10,
      "mem_mb": 50,
      "swappiness": 0,
      "installer_mem_mb": 250
    },
    "docker_runtime": ""
  },
  "seconds": 2.785506248474121,
  "total_seconds": 3.2397704124450684,
  "stats": {
    "reqs_per_sec": 35.900461074361125
  },
  "ol-stats": {
    "Channel().cnt": 100,
    "Channel().ms-avg": 0,
    "Create().cnt": 101,
    "Create().ms-avg": 7,
    "Create()/acquire-cgroup.cnt": 101,
    "Create()/acquire-cgroup.ms-avg": 0,
    "Create()/acquire-mem.cnt": 101,
    "Create()/acquire-mem.ms-avg": 0,
    "Create()/fork-proc.cnt": 100,
    "Create()/fork-proc.ms-avg": 5,
    "Create()/fresh-proc.cnt": 1,
    "Create()/fresh-proc.ms-avg": 138,
    "Create()/make-root-fs.cnt": 101,
    "Create()/make-root-fs.ms-avg": 0,
    "Destroy().cnt": 157,
    "Destroy().ms-avg": 4,
    "Destroy()/detach-root.cnt": 101,
    "Destroy()/detach-root.ms-avg": 0,
    "Destroy()/kill-procs.cnt": 101,
    "Destroy()/kill-procs.ms-avg": 3,
    "Destroy()/remove-root.cnt": 101,
    "Destroy()/remove-root.ms-avg": 0,
    "LambdaFunc.Invoke.cnt": 100,
    "LambdaFunc.Invoke.ms-avg": 24,
    "Pause().cnt": 200,
    "Pause().ms-avg": 9,
    "ServeHTTP.cnt": 100,
    "ServeHTTP.ms-avg": 5,
    "Unpause().cnt": 99,
    "Unpause().ms-avg": 0,
    "childExit().cnt": 100,
    "childExit().ms-avg": 3,
    "evict.cnt": 56,
    "evict.ms-avg": 10,
    "fork().cnt": 100,
    "fork().ms-avg": 5,
    "forkRequest.cnt": 100,
    "forkRequest.ms-avg": 4,
    "fresh-cgroup.cnt": 68,
    "fresh-cgroup.ms-avg": 0,
    "move-to-cg-after-fork.cnt": 100,
    "move-to-cg-after-fork.ms-avg": 0,
    "pull-lambda.cnt": 100,
    "pull-lambda.ms-avg": 3,
    "web-request.cnt": 100,
    "web-request.ms-avg": 24
  },
  "errors": [],
  "worker_tail": [
    "2020/09/12 21:48:26 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:48:26 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:48:26 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:48:26 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:48:26 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:48:26 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:48:26 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2020/09/12 21:48:26 remove worker.pid",
    "2020/09/12 21:48:26 exiting",
    ""
  ]
}
POP conf: {'registry': '/tmp/tmpzua_s1lo'}
PUSH conf: {'registry': '/tmp/tmpi0reo8wi'}
========================================
call_each_once_exec {'lambda_count': 1000, 'alloc_mb': 10}
========================================
RUN ./ol worker -p=test-dir --detach
RUN ./ol kill -p=test-dir
{
  "test": "call_each_once_exec",
  "params": {
    "lambda_count": 1000,
    "alloc_mb": 10
  },
  "pass": true,
  "conf": {
    "SOCK_base_path": "/root/open-lambda/test-dir/lambda",
    "sandbox_config": {},
    "server_mode": "lambda",
    "registry_cache_ms": 5000,
    "trace": {
      "cgroups": false,
      "evictor": false,
      "memory": false,
      "package": false
    },
    "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages",
    "docker_runtime": "",
    "sandbox": "sock",
    "import_cache_tree": "",
    "worker_dir": "/root/open-lambda/test-dir/worker",
    "worker_port": "5000",
    "storage": {
      "root": "private",
      "scratch": "",
      "code": ""
    },
    "pip_mirror": "",
    "registry": "/tmp/tmpi0reo8wi",
    "mem_pool_mb": 500,
    "limits": {
      "procs": 10,
      "mem_mb": 50,
      "swappiness": 0,
      "installer_mem_mb": 250
    },
    "features": {
      "downsize_paused_mem": true,
      "import_cache": true,
      "reuse_cgroups": true
    }
  },
  "seconds": 43.333696126937866,
  "total_seconds": 43.875084400177,
  "stats": {
    "reqs_per_sec": 23.07674295238714
  },
  "ol-stats": {
    "Channel().cnt": 1000,
    "Channel().ms-avg": 0,
    "Create().cnt": 1001,
    "Create().ms-avg": 7,
    "Create()/acquire-cgroup.cnt": 1001,
    "Create()/acquire-cgroup.ms-avg": 0,
    "Create()/acquire-mem.cnt": 1001,
    "Create()/acquire-mem.ms-avg": 0,
    "Create()/fork-proc.cnt": 1000,
    "Create()/fork-proc.ms-avg": 6,
    "Create()/fresh-proc.cnt": 1,
    "Create()/fresh-proc.ms-avg": 137,
    "Create()/make-root-fs.cnt": 1001,
    "Create()/make-root-fs.ms-avg": 1,
    "Destroy().cnt": 1980,
    "Destroy().ms-avg": 5,
    "Destroy()/detach-root.cnt": 1001,
    "Destroy()/detach-root.ms-avg": 0,
    "Destroy()/kill-procs.cnt": 1001,
    "Destroy()/kill-procs.ms-avg": 7,
    "Destroy()/remove-root.cnt": 1001,
    "Destroy()/remove-root.ms-avg": 0,
    "LambdaFunc.Invoke.cnt": 1000,
    "LambdaFunc.Invoke.ms-avg": 39,
    "Pause().cnt": 2000,
    "Pause().ms-avg": 8,
    "ServeHTTP.cnt": 1000,
    "ServeHTTP.ms-avg": 19,
    "Unpause().cnt": 999,
    "Unpause().ms-avg": 0,
    "childExit().cnt": 1000,
    "childExit().ms-avg": 2,
    "evict.cnt": 979,
    "evict.ms-avg": 10,
    "fork().cnt": 1000,
    "fork().ms-avg": 6,
    "forkRequest.cnt": 1000,
    "forkRequest.ms-avg": 5,
    "fresh-cgroup.cnt": 41,
    "fresh-cgroup.ms-avg": 0,
    "move-to-cg-after-fork.cnt": 1000,
    "move-to-cg-after-fork.ms-avg": 0,
    "pull-lambda.cnt": 1000,
    "pull-lambda.ms-avg": 3,
    "web-request.cnt": 1000,
    "web-request.ms-avg": 39
  },
  "errors": [],
  "worker_tail": [
    "2020/09/12 21:49:10 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:49:10 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:49:10 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:49:10 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:49:10 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:49:10 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]",
    "2020/09/12 21:49:10 save stats to /root/open-lambda/test-dir/worker/stats.json",
    "2020/09/12 21:49:10 remove worker.pid",
    "2020/09/12 21:49:10 exiting",
    ""
  ]
}
POP conf: {'registry': '/tmp/tmpi0reo8wi'}
POP conf: {'features': {'reuse_cgroups': True}}
POP conf: {'limits': {'installer_mem_mb': 250}}
PASSED: 16, FAILED: 2
Makefile:27: recipe for target 'test-all' failed
make: *** [test-all] Error 2