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": { "features": { "import_cache": true, "downsize_paused_mem": true, "reuse_cgroups": false }, "import_cache_tree": "", "worker_port": "5000", "registry": "/root/open-lambda/test-registry", "worker_dir": "/root/open-lambda/test-dir/worker", "registry_cache_ms": 5000, "sandbox_config": {}, "server_mode": "lambda", "SOCK_base_path": "/root/open-lambda/test-dir/lambda", "docker_runtime": "", "limits": { "installer_mem_mb": 250, "swappiness": 0, "mem_mb": 50, "procs": 10 }, "sandbox": "sock", "trace": { "package": false, "cgroups": false, "memory": false, "evictor": false }, "mem_pool_mb": 500, "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages", "storage": { "scratch": "", "root": "private", "code": "" }, "pip_mirror": "" }, "seconds": 2.2175896167755127, "total_seconds": 2.5122175216674805, "stats": { "pings_per_sec": 450.94437723654994 }, "ol-stats": { "fresh-cgroup.cnt": 17, "fresh-cgroup.ms-avg": 0 }, "errors": [], "worker_tail": [ "2019/11/01 21:45:11 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:11 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:11 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:11 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:11 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:11 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:11 save stats to /root/open-lambda/test-dir/worker/stats.json", "2019/11/01 21:45:11 remove worker.pid", "2019/11/01 21:45:11 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": { "features": { "import_cache": false, "downsize_paused_mem": true, "reuse_cgroups": false }, "import_cache_tree": "", "worker_port": "5000", "registry": "/root/open-lambda/test-registry", "worker_dir": "/root/open-lambda/test-dir/worker", "registry_cache_ms": 5000, "sandbox": "sock", "server_mode": "lambda", "SOCK_base_path": "/root/open-lambda/test-dir/lambda", "docker_runtime": "", "limits": { "installer_mem_mb": 250, "swappiness": 0, "mem_mb": 50, "procs": 10 }, "sandbox_config": {}, "trace": { "package": false, "cgroups": false, "memory": false, "evictor": false }, "mem_pool_mb": 500, "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages", "storage": { "scratch": "", "root": "private", "code": "" }, "pip_mirror": "" }, "seconds": 10.36206340789795, "total_seconds": 10.604857206344604, "stats": null, "ol-stats": { "Channel().cnt": 10, "Channel().ms-avg": 0, "Create().cnt": 10, "Create().ms-avg": 264, "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": 262, "Create()/make-root-fs.cnt": 10, "Create()/make-root-fs.ms-avg": 1, "Destroy().cnt": 10, "Destroy().ms-avg": 3, "Destroy()/detach-root.cnt": 10, "Destroy()/detach-root.ms-avg": 0, "Destroy()/kill-procs.cnt": 10, "Destroy()/kill-procs.ms-avg": 2, "Destroy()/remove-root.cnt": 10, "Destroy()/remove-root.ms-avg": 0, "LambdaFunc.Invoke.cnt": 4, "LambdaFunc.Invoke.ms-avg": 2580, "Pause().cnt": 4, "Pause().ms-avg": 3, "ServeHTTP.cnt": 4, "ServeHTTP.ms-avg": 71, "fresh-cgroup.cnt": 27, "fresh-cgroup.ms-avg": 0, "pull-lambda.cnt": 4, "pull-lambda.ms-avg": 5, "pull-package.cnt": 6, "pull-package.ms-avg": 1374, "web-request.cnt": 4, "web-request.ms-avg": 2580 }, "errors": [], "worker_tail": [ "2019/11/01 21:45:21 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:21 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:21 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:21 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:21 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:21 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:21 save stats to /root/open-lambda/test-dir/worker/stats.json", "2019/11/01 21:45:21 remove worker.pid", "2019/11/01 21:45:21 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": { "features": { "import_cache": true, "downsize_paused_mem": true, "reuse_cgroups": false }, "import_cache_tree": "", "worker_port": "5000", "docker_runtime": "", "worker_dir": "/root/open-lambda/test-dir/worker", "registry_cache_ms": 5000, "server_mode": "lambda", "SOCK_base_path": "/root/open-lambda/test-dir/lambda", "registry": "/root/open-lambda/test-registry", "limits": { "installer_mem_mb": 250, "swappiness": 0, "mem_mb": 50, "procs": 10 }, "sandbox": "sock", "trace": { "package": false, "evictor": false, "memory": false, "cgroups": false }, "mem_pool_mb": 500, "pip_mirror": "", "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages", "storage": { "scratch": "", "root": "private", "code": "" }, "sandbox_config": {} }, "seconds": 6.101685285568237, "total_seconds": 6.340294599533081, "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": 3, "Destroy()/detach-root.cnt": 11, "Destroy()/detach-root.ms-avg": 0, "Destroy()/kill-procs.cnt": 11, "Destroy()/kill-procs.ms-avg": 2, "Destroy()/remove-root.cnt": 11, "Destroy()/remove-root.ms-avg": 0, "LambdaFunc.Invoke.cnt": 4, "LambdaFunc.Invoke.ms-avg": 1517, "Pause().cnt": 8, "Pause().ms-avg": 4, "ServeHTTP.cnt": 4, "ServeHTTP.ms-avg": 47, "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": 943, "web-request.cnt": 4, "web-request.ms-avg": 1517 }, "errors": [], "worker_tail": [ "2019/11/01 21:45:27 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:27 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:27 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:27 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:27 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:27 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:27 save stats to /root/open-lambda/test-dir/worker/stats.json", "2019/11/01 21:45:27 remove worker.pid", "2019/11/01 21:45:27 exiting", "" ] } POP conf: {'mem_pool_mb': 500} PUSH conf: {'features': {'import_cache': False}, 'sandbox': 'docker'} ======================================== install_tests ======================================== RUN ./ol worker -p=test-dir --detach RUN ./ol kill -p=test-dir { "test": "install_tests", "params": {}, "pass": true, "conf": { "features": { "import_cache": false, "downsize_paused_mem": true, "reuse_cgroups": false }, "import_cache_tree": "", "worker_port": "5000", "registry": "/root/open-lambda/test-registry", "worker_dir": "/root/open-lambda/test-dir/worker", "registry_cache_ms": 5000, "sandbox_config": {}, "server_mode": "lambda", "SOCK_base_path": "/root/open-lambda/test-dir/lambda", "docker_runtime": "", "limits": { "installer_mem_mb": 250, "swappiness": 0, "mem_mb": 50, "procs": 10 }, "sandbox": "docker", "trace": { "package": false, "cgroups": false, "memory": false, "evictor": false }, "mem_pool_mb": 500, "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages", "storage": { "scratch": "", "root": "private", "code": "" }, "pip_mirror": "" }, "seconds": 19.875142812728882, "total_seconds": 20.904194831848145, "stats": null, "ol-stats": { "Channel().cnt": 10, "Channel().ms-avg": 0, "Create().cnt": 10, "Create().ms-avg": 919, "Destroy().cnt": 10, "Destroy().ms-avg": 239, "LambdaFunc.Invoke.cnt": 4, "LambdaFunc.Invoke.ms-avg": 4956, "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": 2294, "web-request.cnt": 4, "web-request.ms-avg": 4956 }, "errors": [], "worker_tail": [ "2019/11/01 21:45:48 Kill function: echo", "2019/11/01 21:45:48 Destroy() [SB 0]", "2019/11/01 21:45:48 Kill function: install", "2019/11/01 21:45:48 Destroy() [SB 6]", "2019/11/01 21:45:48 Kill function: install2", "2019/11/01 21:45:48 Destroy() [SB 7]", "2019/11/01 21:45:48 save stats to /root/open-lambda/test-dir/worker/stats.json", "2019/11/01 21:45:48 remove worker.pid", "2019/11/01 21:45:48 exiting", "" ] } POP conf: {'features': {'import_cache': False}, 'sandbox': 'docker'} ======================================== fork_bomb ======================================== RUN ./ol worker -p=test-dir --detach RUN ./ol kill -p=test-dir { "test": "fork_bomb", "params": {}, "pass": true, "conf": { "features": { "import_cache": true, "downsize_paused_mem": true, "reuse_cgroups": false }, "import_cache_tree": "", "worker_port": "5000", "docker_runtime": "", "worker_dir": "/root/open-lambda/test-dir/worker", "registry_cache_ms": 5000, "server_mode": "lambda", "SOCK_base_path": "/root/open-lambda/test-dir/lambda", "registry": "/root/open-lambda/test-registry", "limits": { "installer_mem_mb": 250, "swappiness": 0, "mem_mb": 50, "procs": 10 }, "sandbox": "sock", "trace": { "package": false, "evictor": false, "memory": false, "cgroups": false }, "mem_pool_mb": 500, "pip_mirror": "", "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages", "storage": { "scratch": "", "root": "private", "code": "" }, "sandbox_config": {} }, "seconds": 0.17986845970153809, "total_seconds": 0.4123053550720215, "stats": null, "ol-stats": { "Channel().cnt": 1, "Channel().ms-avg": 0, "Create().cnt": 2, "Create().ms-avg": 76, "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": 7, "Create()/fresh-proc.cnt": 1, "Create()/fresh-proc.ms-avg": 136, "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": 176, "Pause().cnt": 2, "Pause().ms-avg": 4, "ServeHTTP.cnt": 1, "ServeHTTP.ms-avg": 13, "childExit().cnt": 1, "childExit().ms-avg": 0, "fork().cnt": 1, "fork().ms-avg": 7, "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": 176 }, "errors": [], "worker_tail": [ "2019/11/01 21:45:49 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:49 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:49 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:49 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:49 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:49 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:49 save stats to /root/open-lambda/test-dir/worker/stats.json", "2019/11/01 21:45:49 remove worker.pid", "2019/11/01 21:45:49 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": { "features": { "import_cache": true, "downsize_paused_mem": true, "reuse_cgroups": false }, "import_cache_tree": "", "worker_port": "5000", "docker_runtime": "", "worker_dir": "/root/open-lambda/test-dir/worker", "registry_cache_ms": 5000, "server_mode": "lambda", "SOCK_base_path": "/root/open-lambda/test-dir/lambda", "registry": "/root/open-lambda/test-registry", "limits": { "installer_mem_mb": 250, "swappiness": 0, "mem_mb": 50, "procs": 10 }, "sandbox": "sock", "trace": { "package": false, "evictor": false, "memory": false, "cgroups": false }, "mem_pool_mb": 500, "pip_mirror": "", "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages", "storage": { "scratch": "", "root": "private", "code": "" }, "sandbox_config": {} }, "seconds": 0.8939611911773682, "total_seconds": 1.1278657913208008, "stats": null, "ol-stats": { "Channel().cnt": 1, "Channel().ms-avg": 0, "Create().cnt": 2, "Create().ms-avg": 77, "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": 5, "Create()/fresh-proc.cnt": 1, "Create()/fresh-proc.ms-avg": 139, "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": 890, "Pause().cnt": 2, "Pause().ms-avg": 6, "ServeHTTP.cnt": 1, "ServeHTTP.ms-avg": 705, "childExit().cnt": 1, "childExit().ms-avg": 0, "fork().cnt": 1, "fork().ms-avg": 5, "forkRequest.cnt": 1, "forkRequest.ms-avg": 4, "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": 890 }, "errors": [], "worker_tail": [ "2019/11/01 21:45:50 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:50 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:50 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:50 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:50 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:50 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:45:50 save stats to /root/open-lambda/test-dir/worker/stats.json", "2019/11/01 21:45:50 remove worker.pid", "2019/11/01 21:45:50 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": true, "conf": { "features": { "import_cache": true, "downsize_paused_mem": true, "reuse_cgroups": false }, "import_cache_tree": "", "worker_port": "5000", "docker_runtime": "", "worker_dir": "/root/open-lambda/test-dir/worker", "registry_cache_ms": 5000, "server_mode": "lambda", "SOCK_base_path": "/root/open-lambda/test-dir/lambda", "registry": "/root/open-lambda/test-registry", "limits": { "installer_mem_mb": 250, "swappiness": 0, "mem_mb": 50, "procs": 10 }, "sandbox_config": {}, "trace": { "package": false, "evictor": false, "memory": false, "cgroups": false }, "mem_pool_mb": 500, "pip_mirror": "", "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages", "storage": { "scratch": "", "root": "private", "code": "" }, "sandbox": "sock" }, "seconds": 58.69655704498291, "total_seconds": 58.968133211135864, "stats": null, "ol-stats": { "Channel().cnt": 12, "Channel().ms-avg": 0, "Create().cnt": 13, "Create().ms-avg": 105, "Create()/acquire-cgroup.cnt": 13, "Create()/acquire-cgroup.ms-avg": 0, "Create()/acquire-mem.cnt": 13, "Create()/acquire-mem.ms-avg": 0, "Create()/fork-proc.cnt": 5, "Create()/fork-proc.ms-avg": 7, "Create()/fresh-proc.cnt": 8, "Create()/fresh-proc.ms-avg": 148, "Create()/make-root-fs.cnt": 13, "Create()/make-root-fs.ms-avg": 11, "Destroy().cnt": 13, "Destroy().ms-avg": 4, "Destroy()/detach-root.cnt": 13, "Destroy()/detach-root.ms-avg": 0, "Destroy()/kill-procs.cnt": 13, "Destroy()/kill-procs.ms-avg": 3, "Destroy()/remove-root.cnt": 13, "Destroy()/remove-root.ms-avg": 0, "LambdaFunc.Invoke.cnt": 5, "LambdaFunc.Invoke.ms-avg": 11734, "Pause().cnt": 10, "Pause().ms-avg": 4, "ServeHTTP.cnt": 5, "ServeHTTP.ms-avg": 328, "Unpause().cnt": 4, "Unpause().ms-avg": 0, "childExit().cnt": 5, "childExit().ms-avg": 0, "fork().cnt": 5, "fork().ms-avg": 7, "forkRequest.cnt": 5, "forkRequest.ms-avg": 5, "fresh-cgroup.cnt": 30, "fresh-cgroup.ms-avg": 0, "move-to-cg-after-fork.cnt": 5, "move-to-cg-after-fork.ms-avg": 0, "pull-lambda.cnt": 5, "pull-lambda.ms-avg": 5, "pull-package.cnt": 7, "pull-package.ms-avg": 8093, "web-request.cnt": 5, "web-request.ms-avg": 11734 }, "errors": [], "worker_tail": [ "2019/11/01 21:46:49 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:46:49 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:46:49 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:46:49 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:46:49 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:46:49 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:46:49 save stats to /root/open-lambda/test-dir/worker/stats.json", "2019/11/01 21:46:49 remove worker.pid", "2019/11/01 21:46:49 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 {'baseline': 0, 'seconds': 5, 'fork': False, 'procs': 1} ======================================== RUN ./ol worker -p=test-dir --detach RUN ./ol kill -p=test-dir { "test": "sock_churn", "params": { "baseline": 0, "seconds": 5, "fork": false, "procs": 1 }, "pass": true, "conf": { "features": { "import_cache": true, "downsize_paused_mem": true, "reuse_cgroups": false }, "import_cache_tree": "", "worker_port": "5000", "docker_runtime": "", "worker_dir": "/root/open-lambda/test-dir/worker", "registry_cache_ms": 5000, "server_mode": "sock", "SOCK_base_path": "/root/open-lambda/test-dir/lambda", "registry": "/root/open-lambda/test-dir/registry", "limits": { "installer_mem_mb": 250, "swappiness": 0, "mem_mb": 50, "procs": 10 }, "sandbox_config": {}, "trace": { "package": false, "evictor": false, "memory": false, "cgroups": false }, "mem_pool_mb": 500, "pip_mirror": "", "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages", "storage": { "scratch": "", "root": "private", "code": "" }, "sandbox": "sock" }, "seconds": 5.1901655197143555, "total_seconds": 5.414827585220337, "stats": { "sandboxes_per_sec": 5.8 }, "ol-stats": { "Create().cnt": 29, "Create().ms-avg": 152, "Create()/acquire-cgroup.cnt": 29, "Create()/acquire-cgroup.ms-avg": 0, "Create()/acquire-mem.cnt": 29, "Create()/acquire-mem.ms-avg": 0, "Create()/fresh-proc.cnt": 29, "Create()/fresh-proc.ms-avg": 147, "Create()/make-root-fs.cnt": 29, "Create()/make-root-fs.ms-avg": 4, "Destroy().cnt": 58, "Destroy().ms-avg": 1, "Destroy()/detach-root.cnt": 29, "Destroy()/detach-root.ms-avg": 1, "Destroy()/kill-procs.cnt": 29, "Destroy()/kill-procs.ms-avg": 1, "Destroy()/remove-root.cnt": 29, "Destroy()/remove-root.ms-avg": 0, "fresh-cgroup.cnt": 46, "fresh-cgroup.ms-avg": 0 }, "errors": [], "worker_tail": [ "2019/11/01 21:46:54 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:46:54 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:46:54 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:46:54 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:46:54 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:46:54 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:46:54 save stats to /root/open-lambda/test-dir/worker/stats.json", "2019/11/01 21:46:54 remove worker.pid", "2019/11/01 21:46:54 exiting", "" ] } ======================================== sock_churn {'baseline': 0, 'seconds': 10, 'fork': True, 'procs': 1} ======================================== RUN ./ol worker -p=test-dir --detach RUN ./ol kill -p=test-dir { "test": "sock_churn", "params": { "baseline": 0, "seconds": 10, "fork": true, "procs": 1 }, "pass": true, "conf": { "features": { "import_cache": true, "downsize_paused_mem": true, "reuse_cgroups": false }, "import_cache_tree": "", "worker_port": "5000", "docker_runtime": "", "worker_dir": "/root/open-lambda/test-dir/worker", "registry_cache_ms": 5000, "server_mode": "sock", "SOCK_base_path": "/root/open-lambda/test-dir/lambda", "registry": "/root/open-lambda/test-dir/registry", "limits": { "installer_mem_mb": 250, "swappiness": 0, "mem_mb": 50, "procs": 10 }, "sandbox_config": {}, "trace": { "package": false, "evictor": false, "memory": false, "cgroups": false }, "mem_pool_mb": 500, "pip_mirror": "", "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages", "storage": { "scratch": "", "root": "private", "code": "" }, "sandbox": "sock" }, "seconds": 10.180917501449585, "total_seconds": 10.411144495010376, "stats": { "sandboxes_per_sec": 41.8 }, "ol-stats": { "Create().cnt": 419, "Create().ms-avg": 11, "Create()/acquire-cgroup.cnt": 419, "Create()/acquire-cgroup.ms-avg": 0, "Create()/acquire-mem.cnt": 419, "Create()/acquire-mem.ms-avg": 0, "Create()/fork-proc.cnt": 418, "Create()/fork-proc.ms-avg": 6, "Create()/fresh-proc.cnt": 1, "Create()/fresh-proc.ms-avg": 134, "Create()/make-root-fs.cnt": 419, "Create()/make-root-fs.ms-avg": 3, "Destroy().cnt": 837, "Destroy().ms-avg": 1, "Destroy()/detach-root.cnt": 419, "Destroy()/detach-root.ms-avg": 1, "Destroy()/kill-procs.cnt": 419, "Destroy()/kill-procs.ms-avg": 0, "Destroy()/remove-root.cnt": 419, "Destroy()/remove-root.ms-avg": 0, "childExit().cnt": 418, "childExit().ms-avg": 0, "fork().cnt": 418, "fork().ms-avg": 6, "forkRequest.cnt": 418, "forkRequest.ms-avg": 6, "fresh-cgroup.cnt": 436, "fresh-cgroup.ms-avg": 0, "move-to-cg-after-fork.cnt": 418, "move-to-cg-after-fork.ms-avg": 0 }, "errors": [], "worker_tail": [ "2019/11/01 21:47:05 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:05 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:05 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:05 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:05 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:05 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:05 save stats to /root/open-lambda/test-dir/worker/stats.json", "2019/11/01 21:47:05 remove worker.pid", "2019/11/01 21:47:05 exiting", "" ] } ======================================== sock_churn {'baseline': 0, 'seconds': 10, 'fork': True, 'procs': 15} ======================================== RUN ./ol worker -p=test-dir --detach RUN ./ol kill -p=test-dir { "test": "sock_churn", "params": { "baseline": 0, "seconds": 10, "fork": true, "procs": 15 }, "pass": true, "conf": { "features": { "import_cache": true, "downsize_paused_mem": true, "reuse_cgroups": false }, "import_cache_tree": "", "worker_port": "5000", "docker_runtime": "", "worker_dir": "/root/open-lambda/test-dir/worker", "registry_cache_ms": 5000, "server_mode": "sock", "SOCK_base_path": "/root/open-lambda/test-dir/lambda", "registry": "/root/open-lambda/test-dir/registry", "limits": { "installer_mem_mb": 250, "swappiness": 0, "mem_mb": 50, "procs": 10 }, "sandbox_config": {}, "trace": { "package": false, "evictor": false, "memory": false, "cgroups": false }, "mem_pool_mb": 500, "pip_mirror": "", "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages", "storage": { "scratch": "", "root": "private", "code": "" }, "sandbox": "sock" }, "seconds": 10.385251522064209, "total_seconds": 10.622001886367798, "stats": { "sandboxes_per_sec": 43.6 }, "ol-stats": { "Create().cnt": 437, "Create().ms-avg": 103, "Create()/acquire-cgroup.cnt": 437, "Create()/acquire-cgroup.ms-avg": 0, "Create()/acquire-mem.cnt": 437, "Create()/acquire-mem.ms-avg": 14, "Create()/fork-proc.cnt": 436, "Create()/fork-proc.ms-avg": 85, "Create()/fresh-proc.cnt": 1, "Create()/fresh-proc.ms-avg": 135, "Create()/make-root-fs.cnt": 437, "Create()/make-root-fs.ms-avg": 2, "Destroy().cnt": 873, "Destroy().ms-avg": 62, "Destroy()/detach-root.cnt": 437, "Destroy()/detach-root.ms-avg": 0, "Destroy()/kill-procs.cnt": 437, "Destroy()/kill-procs.ms-avg": 14, "Destroy()/remove-root.cnt": 437, "Destroy()/remove-root.ms-avg": 0, "childExit().cnt": 436, "childExit().ms-avg": 84, "fork().cnt": 436, "fork().ms-avg": 85, "forkRequest.cnt": 436, "forkRequest.ms-avg": 11, "fresh-cgroup.cnt": 454, "fresh-cgroup.ms-avg": 3, "move-to-cg-after-fork.cnt": 436, "move-to-cg-after-fork.ms-avg": 2 }, "errors": [], "worker_tail": [ "2019/11/01 21:47:15 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:15 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:15 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:15 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:15 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:15 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:15 save stats to /root/open-lambda/test-dir/worker/stats.json", "2019/11/01 21:47:15 remove worker.pid", "2019/11/01 21:47:15 exiting", "" ] } ======================================== sock_churn {'baseline': 32, 'seconds': 10, 'fork': True, 'procs': 1} ======================================== RUN ./ol worker -p=test-dir --detach RUN ./ol kill -p=test-dir { "test": "sock_churn", "params": { "baseline": 32, "seconds": 10, "fork": true, "procs": 1 }, "pass": true, "conf": { "features": { "import_cache": true, "downsize_paused_mem": true, "reuse_cgroups": false }, "import_cache_tree": "", "worker_port": "5000", "docker_runtime": "", "worker_dir": "/root/open-lambda/test-dir/worker", "registry_cache_ms": 5000, "server_mode": "sock", "SOCK_base_path": "/root/open-lambda/test-dir/lambda", "registry": "/root/open-lambda/test-dir/registry", "limits": { "installer_mem_mb": 250, "swappiness": 0, "mem_mb": 50, "procs": 10 }, "sandbox_config": {}, "trace": { "package": false, "evictor": false, "memory": false, "cgroups": false }, "mem_pool_mb": 500, "pip_mirror": "", "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages", "storage": { "scratch": "", "root": "private", "code": "" }, "sandbox": "sock" }, "seconds": 11.035848379135132, "total_seconds": 11.373866081237793, "stats": { "sandboxes_per_sec": 42.0 }, "ol-stats": { "Create().cnt": 453, "Create().ms-avg": 7, "Create()/acquire-cgroup.cnt": 453, "Create()/acquire-cgroup.ms-avg": 0, "Create()/acquire-mem.cnt": 453, "Create()/acquire-mem.ms-avg": 0, "Create()/fork-proc.cnt": 452, "Create()/fork-proc.ms-avg": 7, "Create()/fresh-proc.cnt": 1, "Create()/fresh-proc.ms-avg": 137, "Create()/make-root-fs.cnt": 453, "Create()/make-root-fs.ms-avg": 0, "Destroy().cnt": 873, "Destroy().ms-avg": 1, "Destroy()/detach-root.cnt": 453, "Destroy()/detach-root.ms-avg": 0, "Destroy()/kill-procs.cnt": 453, "Destroy()/kill-procs.ms-avg": 1, "Destroy()/remove-root.cnt": 453, "Destroy()/remove-root.ms-avg": 0, "Pause().cnt": 32, "Pause().ms-avg": 0, "childExit().cnt": 452, "childExit().ms-avg": 0, "fork().cnt": 452, "fork().ms-avg": 7, "forkRequest.cnt": 452, "forkRequest.ms-avg": 5, "fresh-cgroup.cnt": 470, "fresh-cgroup.ms-avg": 0, "move-to-cg-after-fork.cnt": 452, "move-to-cg-after-fork.ms-avg": 0 }, "errors": [], "worker_tail": [ "2019/11/01 21:47:27 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:27 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:27 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:27 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:27 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:27 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:27 save stats to /root/open-lambda/test-dir/worker/stats.json", "2019/11/01 21:47:27 remove worker.pid", "2019/11/01 21:47:27 exiting", "" ] } ======================================== sock_churn {'baseline': 32, 'seconds': 10, 'fork': True, 'procs': 15} ======================================== RUN ./ol worker -p=test-dir --detach RUN ./ol kill -p=test-dir { "test": "sock_churn", "params": { "baseline": 32, "seconds": 10, "fork": true, "procs": 15 }, "pass": true, "conf": { "features": { "import_cache": true, "downsize_paused_mem": true, "reuse_cgroups": false }, "import_cache_tree": "", "worker_port": "5000", "docker_runtime": "", "worker_dir": "/root/open-lambda/test-dir/worker", "registry_cache_ms": 5000, "server_mode": "sock", "SOCK_base_path": "/root/open-lambda/test-dir/lambda", "registry": "/root/open-lambda/test-dir/registry", "limits": { "installer_mem_mb": 250, "swappiness": 0, "mem_mb": 50, "procs": 10 }, "sandbox_config": {}, "trace": { "package": false, "evictor": false, "memory": false, "cgroups": false }, "mem_pool_mb": 500, "pip_mirror": "", "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages", "storage": { "scratch": "", "root": "private", "code": "" }, "sandbox": "sock" }, "seconds": 11.000711917877197, "total_seconds": 11.351543664932251, "stats": { "sandboxes_per_sec": 36.9 }, "ol-stats": { "Create().cnt": 402, "Create().ms-avg": 153, "Create()/acquire-cgroup.cnt": 402, "Create()/acquire-cgroup.ms-avg": 0, "Create()/acquire-mem.cnt": 402, "Create()/acquire-mem.ms-avg": 79, "Create()/fork-proc.cnt": 401, "Create()/fork-proc.ms-avg": 66, "Create()/fresh-proc.cnt": 1, "Create()/fresh-proc.ms-avg": 143, "Create()/make-root-fs.cnt": 402, "Create()/make-root-fs.ms-avg": 3, "Destroy().cnt": 771, "Destroy().ms-avg": 60, "Destroy()/detach-root.cnt": 402, "Destroy()/detach-root.ms-avg": 0, "Destroy()/kill-procs.cnt": 402, "Destroy()/kill-procs.ms-avg": 13, "Destroy()/remove-root.cnt": 402, "Destroy()/remove-root.ms-avg": 0, "Pause().cnt": 32, "Pause().ms-avg": 0, "childExit().cnt": 401, "childExit().ms-avg": 76, "fork().cnt": 401, "fork().ms-avg": 66, "forkRequest.cnt": 401, "forkRequest.ms-avg": 11, "fresh-cgroup.cnt": 419, "fresh-cgroup.ms-avg": 2, "move-to-cg-after-fork.cnt": 401, "move-to-cg-after-fork.ms-avg": 2 }, "errors": [], "worker_tail": [ "2019/11/01 21:47:38 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:38 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:38 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:38 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:38 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:38 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:38 save stats to /root/open-lambda/test-dir/worker/stats.json", "2019/11/01 21:47:38 remove worker.pid", "2019/11/01 21:47:38 exiting", "" ] } POP conf: {'server_mode': 'sock', 'mem_pool_mb': 500} PUSH conf: {'registry': '/tmp/tmp4npm8u27', '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": { "features": { "import_cache": true, "downsize_paused_mem": true, "reuse_cgroups": false }, "import_cache_tree": "", "worker_port": "5000", "registry": "/tmp/tmp4npm8u27", "worker_dir": "/root/open-lambda/test-dir/worker", "registry_cache_ms": 3000, "sandbox": "sock", "server_mode": "lambda", "SOCK_base_path": "/root/open-lambda/test-dir/lambda", "docker_runtime": "", "limits": { "installer_mem_mb": 250, "swappiness": 0, "mem_mb": 50, "procs": 10 }, "sandbox_config": {}, "trace": { "package": false, "cgroups": false, "memory": false, "evictor": false }, "mem_pool_mb": 500, "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages", "storage": { "scratch": "", "root": "private", "code": "" }, "pip_mirror": "" }, "seconds": null, "total_seconds": 3.286653757095337, "stats": null, "ol-stats": { "Channel().cnt": 2, "Channel().ms-avg": 0, "Create().cnt": 3, "Create().ms-avg": 427, "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": 1257, "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": 2, "Destroy()/remove-root.cnt": 3, "Destroy()/remove-root.ms-avg": 0, "LambdaFunc.Invoke.cnt": 495, "LambdaFunc.Invoke.ms-avg": 3, "Pause().cnt": 497, "Pause().ms-avg": 0, "ServeHTTP.cnt": 495, "ServeHTTP.ms-avg": 0, "Unpause().cnt": 494, "Unpause().ms-avg": 0, "childExit().cnt": 2, "childExit().ms-avg": 0, "fork().cnt": 2, "fork().ms-avg": 6, "forkRequest.cnt": 2, "forkRequest.ms-avg": 6, "fresh-cgroup.cnt": 20, "fresh-cgroup.ms-avg": 0, "move-to-cg-after-fork.cnt": 2, "move-to-cg-after-fork.ms-avg": 0, "pull-lambda.cnt": 2, "pull-lambda.ms-avg": 4, "web-request.cnt": 495, "web-request.ms-avg": 3 }, "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", "2019/11/01 21:47:38 save PID 17568 to file /root/open-lambda/test-dir/worker/worker.pid", "2019/11/01 21:47:38 Start Lambda Server", "2019/11/01 21:47:38 Storage dir at /root/open-lambda/test-dir/worker/code", "2019/11/01 21:47:38 Storage dir at /root/open-lambda/test-dir/worker/scratch", "2019/11/01 21:47:38 Create SandboxPool", "2019/11/01 21:47:38 create /sys/fs/cgroup/blkio/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:38 create /sys/fs/cgroup/cpu/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:38 create /sys/fs/cgroup/devices/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:38 create /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:38 create /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:38 create /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:38 create /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:38 create /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:38 create /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:38 Storage dir at /root/open-lambda/test-dir/worker/root-sandboxes", "2019/11/01 21:47:38 Create DepTracer", "2019/11/01 21:47:38 Create PackagePuller", "2019/11/01 21:47:38 Create ImportCache", "2019/11/01 21:47:38 Import Cache Tree:", "2019/11/01 21:47:38 0 - ROOT", "2019/11/01 21:47:38 Create HandlerPuller", "2019/11/01 21:47:38 Setups Handlers", "2019/11/01 21:47:38 Execute handler by POSTing to localhost:5000/run/", "2019/11/01 21:47:38 Get status by sending request to localhost:5000/status", "2019/11/01 21:47:38 start creating/serving CGs [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:38 Receive request to /pid", "2019/11/01 21:47:38 Receive request to /run/version", "2019/11/01 21:47:38 debug: LambdaFunc.Task() runs on goroutine 19 [FUNC version]", "2019/11/01 21:47:38 increase instances to 1 [FUNC version]", "2019/11/01 21:47:38 Try using Zygote from ", "2019/11/01 21:47:38 .Create(, false, /root/open-lambda/test-dir/worker/code/1003-import-cache, /root/open-lambda/test-dir/worker/scratch/1004-import-cache, )=1... [SOCK POOL sandboxes]", "2019/11/01 21:47:38 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", "2019/11/01 21:47:40 ...returns , [SOCK POOL sandboxes]", "2019/11/01 21:47:40 .Create(, true, /root/open-lambda/test-dir/worker/code/1001-version, /root/open-lambda/test-dir/worker/scratch/1002-version, )=2... [SOCK POOL sandboxes]", "2019/11/01 21:47:40 use cgroup cg-2 [SOCK 2]", "2019/11/01 21:47:40 fork(SB 2) [SB 1]", "sock2.py: start web server on fd: 3", "2019/11/01 21:47:40 move PID 17583 from CG cg-1 to CG cg-2 [SOCK 1]", "2019/11/01 21:47:40 ...returns , [SOCK POOL sandboxes]", "2019/11/01 21:47:40 Pause() [SB 1]", "2019/11/01 21:47:40 Channel() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:40 Receive request to /run/version", "2019/11/01 21:47:40 Unpause() [SB 2]", "2019/11/01 21:47:40 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 Unpause() [SB 2]", "2019/11/01 21:47:41 Pause() [SB 2]", "2019/11/01 21:47:41 Receive request to /run/version", "2019/11/01 21:47:41 increase instances to 1 [FUNC version]", "2019/11/01 21:47:41 Try using Zygote from ", "2019/11/01 21:47:41 Unpause() [SB 1]", "2019/11/01 21:47:41 .Create(, true, /root/open-lambda/test-dir/worker/code/1007-version, /root/open-lambda/test-dir/worker/scratch/1008-version, )=3... [SOCK POOL sandboxes]", "2019/11/01 21:47:41 use cgroup cg-3 [SOCK 3]", "2019/11/01 21:47:41 fork(SB 3) [SB 1]", "sock2.py: start web server on fd: 3", "2019/11/01 21:47:41 Destroy() [SB 2]", "2019/11/01 21:47:41 CG ref count decremented to 0 [SOCK 2]", "2019/11/01 21:47:41 waiting for 1 procs in cg-2 to die [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:41 move PID 17587 from CG cg-1 to CG cg-3 [SOCK 1]", "2019/11/01 21:47:41 ...returns , [SOCK POOL sandboxes]", "2019/11/01 21:47:41 Pause() [SB 1]", "2019/11/01 21:47:41 killed PIDs [17583] in CG [SOCK 2]", "2019/11/01 21:47:41 unmount and remove dirs [SOCK 2]", "2019/11/01 21:47:41 childExit(SB 2) [SB 1]", "2019/11/01 21:47:41 Channel() [SB 3]", "2019/11/01 21:47:41 CG ref count decremented to 2 [SOCK 1]", "2019/11/01 21:47:41 Pause() [SB 3]", "2019/11/01 21:47:41 received kill signal, cleaning up", "2019/11/01 21:47:41 Kill function: version", "2019/11/01 21:47:41 Destroy() [SB 3]", "2019/11/01 21:47:41 CG ref count decremented to 0 [SOCK 3]", "2019/11/01 21:47:41 killed PIDs [17587] in CG [SOCK 3]", "2019/11/01 21:47:41 unmount and remove dirs [SOCK 3]", "2019/11/01 21:47:41 childExit(SB 3) [SB 1]", "2019/11/01 21:47:41 CG ref count decremented to 1 [SOCK 1]", "2019/11/01 21:47:41 Import Cache Tree:", "2019/11/01 21:47:41 2 - ROOT", "2019/11/01 21:47:41 Destroy() [SB 1]", "2019/11/01 21:47:41 CG ref count decremented to 0 [SOCK 1]", "2019/11/01 21:47:41 killed PIDs [17581] in CG [SOCK 1]", "2019/11/01 21:47:41 unmount and remove dirs [SOCK 1]", "2019/11/01 21:47:41 make sure all memory is free [SOCK POOL sandboxes]", "2019/11/01 21:47:41 memory pool emptied [SOCK POOL sandboxes]", "2019/11/01 21:47:41 received shutdown request [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:41 empty queues and release CGs [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:41 remove /sys/fs/cgroup/blkio/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:41 remove /sys/fs/cgroup/cpu/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:41 remove /sys/fs/cgroup/devices/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:41 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:41 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:41 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:41 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:41 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:41 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:41 save stats to /root/open-lambda/test-dir/worker/stats.json", "2019/11/01 21:47:41 remove worker.pid", "2019/11/01 21:47:41 exiting", "" ] } POP conf: {'registry': '/tmp/tmp4npm8u27', 'registry_cache_ms': 3000} PUSH conf: {'registry': '/root/open-lambda/test-registry'} ======================================== stress_one_lambda {'seconds': 15, 'procs': 1} ======================================== RUN ./ol worker -p=test-dir --detach RUN ./ol kill -p=test-dir { "test": "stress_one_lambda", "params": { "seconds": 15, "procs": 1 }, "pass": true, "conf": { "features": { "import_cache": true, "downsize_paused_mem": true, "reuse_cgroups": false }, "import_cache_tree": "", "worker_port": "5000", "docker_runtime": "", "worker_dir": "/root/open-lambda/test-dir/worker", "registry_cache_ms": 5000, "server_mode": "lambda", "SOCK_base_path": "/root/open-lambda/test-dir/lambda", "registry": "/root/open-lambda/test-registry", "limits": { "installer_mem_mb": 250, "swappiness": 0, "mem_mb": 50, "procs": 10 }, "sandbox": "sock", "trace": { "package": false, "evictor": false, "memory": false, "cgroups": false }, "mem_pool_mb": 500, "pip_mirror": "", "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages", "storage": { "scratch": "", "root": "private", "code": "" }, "sandbox_config": {} }, "seconds": 15.046043872833252, "total_seconds": 15.277711868286133, "stats": { "reqs_per_sec": 284.8 }, "ol-stats": { "Channel().cnt": 3, "Channel().ms-avg": 0, "Create().cnt": 4, "Create().ms-avg": 44, "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": 137, "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": 4272, "LambdaFunc.Invoke.ms-avg": 1, "Pause().cnt": 4275, "Pause().ms-avg": 0, "ServeHTTP.cnt": 4272, "ServeHTTP.ms-avg": 0, "Unpause().cnt": 4271, "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": 4272, "web-request.ms-avg": 1 }, "errors": [], "worker_tail": [ "2019/11/01 21:47:57 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:57 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:57 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:57 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:57 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:57 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:47:57 save stats to /root/open-lambda/test-dir/worker/stats.json", "2019/11/01 21:47:57 remove worker.pid", "2019/11/01 21:47:57 exiting", "" ] } ======================================== stress_one_lambda {'seconds': 15, 'procs': 2} ======================================== RUN ./ol worker -p=test-dir --detach RUN ./ol kill -p=test-dir { "test": "stress_one_lambda", "params": { "seconds": 15, "procs": 2 }, "pass": true, "conf": { "features": { "import_cache": true, "downsize_paused_mem": true, "reuse_cgroups": false }, "import_cache_tree": "", "worker_port": "5000", "docker_runtime": "", "worker_dir": "/root/open-lambda/test-dir/worker", "registry_cache_ms": 5000, "server_mode": "lambda", "SOCK_base_path": "/root/open-lambda/test-dir/lambda", "registry": "/root/open-lambda/test-registry", "limits": { "installer_mem_mb": 250, "swappiness": 0, "mem_mb": 50, "procs": 10 }, "sandbox": "sock", "trace": { "package": false, "evictor": false, "memory": false, "cgroups": false }, "mem_pool_mb": 500, "pip_mirror": "", "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages", "storage": { "scratch": "", "root": "private", "code": "" }, "sandbox_config": {} }, "seconds": 15.046857118606567, "total_seconds": 15.27867317199707, "stats": { "reqs_per_sec": 284.53333333333336 }, "ol-stats": { "Channel().cnt": 3, "Channel().ms-avg": 0, "Create().cnt": 4, "Create().ms-avg": 44, "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": 138, "Create()/make-root-fs.cnt": 4, "Create()/make-root-fs.ms-avg": 3, "Destroy().cnt": 4, "Destroy().ms-avg": 4, "Destroy()/detach-root.cnt": 4, "Destroy()/detach-root.ms-avg": 0, "Destroy()/kill-procs.cnt": 4, "Destroy()/kill-procs.ms-avg": 3, "Destroy()/remove-root.cnt": 4, "Destroy()/remove-root.ms-avg": 0, "LambdaFunc.Invoke.cnt": 4268, "LambdaFunc.Invoke.ms-avg": 1, "Pause().cnt": 3750, "Pause().ms-avg": 0, "ServeHTTP.cnt": 4268, "ServeHTTP.ms-avg": 0, "Unpause().cnt": 3746, "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": 4268, "web-request.ms-avg": 1 }, "errors": [], "worker_tail": [ "2019/11/01 21:48:12 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:48:12 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:48:12 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:48:12 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:48:12 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:48:12 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:48:12 save stats to /root/open-lambda/test-dir/worker/stats.json", "2019/11/01 21:48:12 remove worker.pid", "2019/11/01 21:48:12 exiting", "" ] } ======================================== stress_one_lambda {'seconds': 15, 'procs': 8} ======================================== RUN ./ol worker -p=test-dir --detach RUN ./ol kill -p=test-dir { "test": "stress_one_lambda", "params": { "seconds": 15, "procs": 8 }, "pass": true, "conf": { "features": { "import_cache": true, "downsize_paused_mem": true, "reuse_cgroups": false }, "import_cache_tree": "", "worker_port": "5000", "docker_runtime": "", "worker_dir": "/root/open-lambda/test-dir/worker", "registry_cache_ms": 5000, "server_mode": "lambda", "SOCK_base_path": "/root/open-lambda/test-dir/lambda", "registry": "/root/open-lambda/test-registry", "limits": { "installer_mem_mb": 250, "swappiness": 0, "mem_mb": 50, "procs": 10 }, "sandbox": "sock", "trace": { "package": false, "evictor": false, "memory": false, "cgroups": false }, "mem_pool_mb": 500, "pip_mirror": "", "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages", "storage": { "scratch": "", "root": "private", "code": "" }, "sandbox_config": {} }, "seconds": 15.068302392959595, "total_seconds": 15.3014075756073, "stats": { "reqs_per_sec": 267.06666666666666 }, "ol-stats": { "Channel().cnt": 3, "Channel().ms-avg": 0, "Create().cnt": 4, "Create().ms-avg": 44, "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": 8, "Create()/fresh-proc.cnt": 1, "Create()/fresh-proc.ms-avg": 137, "Create()/make-root-fs.cnt": 4, "Create()/make-root-fs.ms-avg": 3, "Destroy().cnt": 4, "Destroy().ms-avg": 5, "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": 4006, "LambdaFunc.Invoke.ms-avg": 2, "Pause().cnt": 3032, "Pause().ms-avg": 0, "ServeHTTP.cnt": 4006, "ServeHTTP.ms-avg": 0, "Unpause().cnt": 3028, "Unpause().ms-avg": 0, "childExit().cnt": 3, "childExit().ms-avg": 2, "fork().cnt": 3, "fork().ms-avg": 8, "forkRequest.cnt": 3, "forkRequest.ms-avg": 7, "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": 3, "web-request.cnt": 4006, "web-request.ms-avg": 2 }, "errors": [], "worker_tail": [ "2019/11/01 21:48:27 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:48:27 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:48:27 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:48:27 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:48:27 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:48:27 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:48:27 save stats to /root/open-lambda/test-dir/worker/stats.json", "2019/11/01 21:48:27 remove worker.pid", "2019/11/01 21:48:27 exiting", "" ] } POP conf: {'registry': '/root/open-lambda/test-registry'} PUSH conf: {'features': {'reuse_cgroups': True}} PUSH conf: {'registry': '/tmp/tmpkdd2uvvl'} ======================================== 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": { "features": { "import_cache": true, "downsize_paused_mem": true, "reuse_cgroups": true }, "import_cache_tree": "", "worker_port": "5000", "registry": "/tmp/tmpkdd2uvvl", "worker_dir": "/root/open-lambda/test-dir/worker", "registry_cache_ms": 5000, "sandbox": "sock", "server_mode": "lambda", "SOCK_base_path": "/root/open-lambda/test-dir/lambda", "docker_runtime": "", "limits": { "installer_mem_mb": 250, "swappiness": 0, "mem_mb": 50, "procs": 10 }, "sandbox_config": {}, "trace": { "package": false, "cgroups": false, "memory": false, "evictor": false }, "mem_pool_mb": 500, "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages", "storage": { "scratch": "", "root": "private", "code": "" }, "pip_mirror": "" }, "seconds": 3.0588879585266113, "total_seconds": 3.4968035221099854, "stats": { "reqs_per_sec": 32.691886647345505 }, "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": 137, "Create()/make-root-fs.cnt": 101, "Create()/make-root-fs.ms-avg": 0, "Destroy().cnt": 159, "Destroy().ms-avg": 5, "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": 26, "Pause().cnt": 200, "Pause().ms-avg": 11, "ServeHTTP.cnt": 100, "ServeHTTP.ms-avg": 6, "Unpause().cnt": 99, "Unpause().ms-avg": 0, "childExit().cnt": 100, "childExit().ms-avg": 4, "evict.cnt": 58, "evict.ms-avg": 12, "fork().cnt": 100, "fork().ms-avg": 5, "forkRequest.cnt": 100, "forkRequest.ms-avg": 4, "fresh-cgroup.cnt": 66, "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": 26 }, "errors": [], "worker_tail": [ "2019/11/01 21:48:31 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:48:31 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:48:31 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:48:31 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:48:31 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:48:31 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:48:31 save stats to /root/open-lambda/test-dir/worker/stats.json", "2019/11/01 21:48:31 remove worker.pid", "2019/11/01 21:48:31 exiting", "" ] } POP conf: {'registry': '/tmp/tmpkdd2uvvl'} PUSH conf: {'registry': '/tmp/tmpoyawv2j8'} ======================================== 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": { "features": { "import_cache": true, "downsize_paused_mem": true, "reuse_cgroups": true }, "import_cache_tree": "", "worker_port": "5000", "docker_runtime": "", "worker_dir": "/root/open-lambda/test-dir/worker", "registry_cache_ms": 5000, "server_mode": "lambda", "SOCK_base_path": "/root/open-lambda/test-dir/lambda", "registry": "/tmp/tmpoyawv2j8", "limits": { "installer_mem_mb": 250, "swappiness": 0, "mem_mb": 50, "procs": 10 }, "sandbox": "sock", "trace": { "package": false, "evictor": false, "memory": false, "cgroups": false }, "mem_pool_mb": 500, "pip_mirror": "", "Pkgs_dir": "/root/open-lambda/test-dir/lambda/packages", "storage": { "scratch": "", "root": "private", "code": "" }, "sandbox_config": {} }, "seconds": 48.46649432182312, "total_seconds": 49.65856671333313, "stats": { "reqs_per_sec": 20.632821201419357 }, "ol-stats": { "Channel().cnt": 1000, "Channel().ms-avg": 0, "Create().cnt": 1001, "Create().ms-avg": 8, "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": 6, "Destroy()/detach-root.cnt": 1001, "Destroy()/detach-root.ms-avg": 0, "Destroy()/kill-procs.cnt": 1001, "Destroy()/kill-procs.ms-avg": 8, "Destroy()/remove-root.cnt": 1001, "Destroy()/remove-root.ms-avg": 0, "LambdaFunc.Invoke.cnt": 1000, "LambdaFunc.Invoke.ms-avg": 44, "Pause().cnt": 2000, "Pause().ms-avg": 9, "ServeHTTP.cnt": 1000, "ServeHTTP.ms-avg": 19, "Unpause().cnt": 999, "Unpause().ms-avg": 0, "childExit().cnt": 1000, "childExit().ms-avg": 3, "evict.cnt": 979, "evict.ms-avg": 12, "fork().cnt": 1000, "fork().ms-avg": 6, "forkRequest.cnt": 1000, "forkRequest.ms-avg": 5, "fresh-cgroup.cnt": 40, "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": 5, "web-request.cnt": 1000, "web-request.ms-avg": 44 }, "errors": [], "worker_tail": [ "2019/11/01 21:49:20 remove /sys/fs/cgroup/freezer/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:49:20 remove /sys/fs/cgroup/hugetlb/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:49:20 remove /sys/fs/cgroup/memory/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:49:20 remove /sys/fs/cgroup/perf_event/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:49:20 remove /sys/fs/cgroup/systemd/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:49:20 remove /sys/fs/cgroup/pids/test-dir-sandboxes [CGROUP POOL test-dir-sandboxes]", "2019/11/01 21:49:21 save stats to /root/open-lambda/test-dir/worker/stats.json", "2019/11/01 21:49:21 remove worker.pid", "2019/11/01 21:49:21 exiting", "" ] } POP conf: {'registry': '/tmp/tmpoyawv2j8'} POP conf: {'features': {'reuse_cgroups': True}} POP conf: {'limits': {'installer_mem_mb': 250}} PASSED: 17, FAILED: 1 Makefile:27: recipe for target 'test-all' failed make: *** [test-all] Error 1