==> Loaded configuration from /home/jerrypeng/.heron/conf/standalone/resources/slave.hcl ==> Starting Nomad agent... ==> Nomad agent configuration: Client: true Log Level: DEBUG Region: global (DC: dc1) Server: false Version: 0.8.1 ==> Nomad agent started! Log data will stream in below: 2018/04/26 05:40:46.324718 [INFO] client: using state directory /tmp/slave/client 2018/04/26 05:40:46.329328 [INFO] client: using alloc directory /tmp/slave/alloc 2018/04/26 05:40:46.332162 [DEBUG] client.fingerprint_manager: built-in fingerprints: [arch cgroup consul cpu host memory network nomad signal storage vault env_aws env_gce] 2018/04/26 05:40:46.332380 [INFO] fingerprint.cgroups: cgroups are available 2018/04/26 05:40:46.333025 [DEBUG] fingerprint.cpu: frequency: 2300 MHz 2018/04/26 05:40:46.333031 [DEBUG] fingerprint.cpu: core count: 4 2018/04/26 05:40:46.333711 [DEBUG] client.fingerprint_manager: fingerprinting cgroup every 15s 2018/04/26 05:40:46.333823 [DEBUG] client.fingerprint_manager: fingerprinting consul every 15s 2018/04/26 05:40:46.336685 [WARN] fingerprint.network: Unable to parse Speed in output of '/sbin/ethtool ens4' 2018/04/26 05:40:46.336742 [DEBUG] fingerprint.network: Unable to parse link speed from /sys/class/net/ens4/speed 2018/04/26 05:40:46.336746 [DEBUG] fingerprint.network: link speed could not be detected and no speed specified by user. Defaulting to 1000 2018/04/26 05:40:46.336799 [DEBUG] fingerprint.network: Detected interface ens4 with IP: 10.128.0.2 2018/04/26 05:40:46.338203 [DEBUG] client.fingerprint_manager: fingerprinting vault every 15s 2018/04/26 05:40:46.354798 [DEBUG] client.fingerprint_manager: detected fingerprints [arch cgroup cpu host network nomad signal storage env_gce] 2018/04/26 05:40:46.354816 [DEBUG] driver.docker: using client connection initialized from environment 2018/04/26 05:40:46.354837 [DEBUG] driver.docker: using client connection initialized from environment 2018/04/26 05:40:46.354977 [DEBUG] driver.docker: could not connect to docker daemon at unix:///var/run/docker.sock: Get http://unix.sock/version: dial unix /var/run/docker.sock: connect: no such file or directory 2018/04/26 05:40:46.355022 [DEBUG] driver.exec: must run as root user, disabling 2018/04/26 05:40:46.355050 [WARN] driver.raw_exec: raw exec is enabled. Only enable if needed 2018/04/26 05:40:46.355065 [INFO] driver.java: root privileges and mounted cgroups required on linux, disabling 2018/04/26 05:40:46.355120 [DEBUG] client.fingerprint_manager: fingerprinting driver exec every 15s 2018/04/26 05:40:46.355143 [DEBUG] client.fingerprint_manager: fingerprinting driver docker every 15s 2018/04/26 05:40:46.355152 [DEBUG] driver.rkt: must run as root user, disabling 2018/04/26 05:40:46.355153 [DEBUG] client.fingerprint_manager: health checking driver docker every 1m0s 2018/04/26 05:40:46.355175 [DEBUG] client.fingerprint_manager: detected drivers [exec raw_exec java] 2018/04/26 05:40:46.355205 [DEBUG] client.fingerprint_manager: fingerprinting driver rkt every 15s 2018/04/26 05:40:46.360735 [INFO] client: Node ID "672cdb7e-a1a2-ab5b-47d7-c15e27856eee" 2018/04/26 05:40:46.363121 [DEBUG] client: updated allocations at index 1 (total 0) (pulled 0) (filtered 0) 2018/04/26 05:40:46.363198 [DEBUG] client: allocs: (added 0) (removed 0) (updated 0) (ignore 0) 2018/04/26 05:40:47.760524 [INFO] client: node registration complete 2018/04/26 05:40:50.174121 [DEBUG] client: state updated to ready 2018/04/26 05:40:50.182710 [DEBUG] client: updated allocations at index 12 (total 1) (pulled 1) (filtered 0) 2018/04/26 05:40:50.182842 [DEBUG] client: allocs: (added 1) (removed 0) (updated 0) (ignore 0) 2018/04/26 05:40:50.201769 [DEBUG] client: starting task runners for alloc '37e517d2-9c2b-58fa-743a-921bfc20dd42' 2018/04/26 05:40:50.201937 [DEBUG] client.alloc_watcher: deadline for alloc "37e517d2-9c2b-58fa-743a-921bfc20dd42" is at 2018-04-26 05:45:50.201901283 +0000 UTC m=+303.917811955 (deploy=false checks=true) 2018/04/26 05:40:50.202000 [DEBUG] client: starting task context for 'apiserver' (alloc '37e517d2-9c2b-58fa-743a-921bfc20dd42') 2018-04-26T05:40:50.218Z [DEBUG] plugin: starting plugin: path=/home/jerrypeng/.heron/bin/heron-nomad args="[/home/jerrypeng/.heron/bin/heron-nomad executor {"LogFile":"/tmp/slave/alloc/37e517d2-9c2b-58fa-743a-921bfc20dd42/apiserver/executor.out","LogLevel":"DEBUG"}]" 2018-04-26T05:40:50.218Z [DEBUG] plugin: waiting for RPC address: path=/home/jerrypeng/.heron/bin/heron-nomad 2018-04-26T05:40:50.236Z [DEBUG] plugin.heron-nomad: plugin address: timestamp=2018-04-26T05:40:50.236Z address=/tmp/plugin258729187 network=unix 2018/04/26 05:40:50.243634 [DEBUG] driver.raw_exec: started process with pid: 28191 2018/04/26 05:40:50.416273 [DEBUG] client: updated allocations at index 14 (total 1) (pulled 0) (filtered 1) 2018/04/26 05:40:50.416339 [DEBUG] client: allocs: (added 0) (removed 0) (updated 0) (ignore 1) 2018/04/26 05:40:50.855164 [DEBUG] client: updated allocations at index 17 (total 2) (pulled 1) (filtered 1) 2018/04/26 05:40:50.855236 [DEBUG] client: allocs: (added 1) (removed 0) (updated 0) (ignore 1) 2018/04/26 05:40:50.868525 [DEBUG] client: starting task runners for alloc 'b8bc00e5-0ecf-4bcb-0dfb-43a6db44b508' 2018/04/26 05:40:50.868811 [DEBUG] client.alloc_watcher: deadline for alloc "b8bc00e5-0ecf-4bcb-0dfb-43a6db44b508" is at 2018-04-26 05:45:50.868774118 +0000 UTC m=+304.584684734 (deploy=false checks=true) 2018/04/26 05:40:50.868819 [DEBUG] client: starting task context for 'heron-ui' (alloc 'b8bc00e5-0ecf-4bcb-0dfb-43a6db44b508') 2018/04/26 05:40:50.868823 [DEBUG] client: starting task context for 'heron-tracker' (alloc 'b8bc00e5-0ecf-4bcb-0dfb-43a6db44b508') 2018-04-26T05:40:50.885Z [DEBUG] plugin: starting plugin: path=/home/jerrypeng/.heron/bin/heron-nomad args="[/home/jerrypeng/.heron/bin/heron-nomad executor {"LogFile":"/tmp/slave/alloc/b8bc00e5-0ecf-4bcb-0dfb-43a6db44b508/heron-tracker/executor.out","LogLevel":"DEBUG"}]" 2018-04-26T05:40:50.886Z [DEBUG] plugin: starting plugin: path=/home/jerrypeng/.heron/bin/heron-nomad args="[/home/jerrypeng/.heron/bin/heron-nomad executor {"LogFile":"/tmp/slave/alloc/b8bc00e5-0ecf-4bcb-0dfb-43a6db44b508/heron-ui/executor.out","LogLevel":"DEBUG"}]" 2018-04-26T05:40:50.890Z [DEBUG] plugin: waiting for RPC address: path=/home/jerrypeng/.heron/bin/heron-nomad 2018-04-26T05:40:50.890Z [DEBUG] plugin: waiting for RPC address: path=/home/jerrypeng/.heron/bin/heron-nomad 2018-04-26T05:40:50.910Z [DEBUG] plugin.heron-nomad: plugin address: timestamp=2018-04-26T05:40:50.910Z address=/tmp/plugin057860860 network=unix 2018-04-26T05:40:50.912Z [DEBUG] plugin.heron-nomad: plugin address: timestamp=2018-04-26T05:40:50.911Z network=unix address=/tmp/plugin547951994 2018/04/26 05:40:50.923225 [DEBUG] driver.raw_exec: started process with pid: 28250 2018/04/26 05:40:50.930990 [DEBUG] driver.raw_exec: started process with pid: 28253 2018/04/26 05:40:51.019063 [DEBUG] client: updated allocations at index 19 (total 2) (pulled 0) (filtered 2) 2018/04/26 05:40:51.019221 [DEBUG] client: allocs: (added 0) (removed 0) (updated 0) (ignore 2) 2018/04/26 05:40:53.912304 [DEBUG] client: state changed, updating node and re-registering. 2018/04/26 05:40:53.919252 [INFO] client: node registration complete 2018/04/26 05:40:59.449412 [DEBUG] client: updated allocations at index 23 (total 3) (pulled 1) (filtered 2) 2018/04/26 05:40:59.450230 [DEBUG] client: allocs: (added 1) (removed 0) (updated 0) (ignore 2) 2018/04/26 05:40:59.485311 [DEBUG] client: starting task runners for alloc '12f136bf-380d-d659-6f87-0c2769488bf8' 2018/04/26 05:40:59.485456 [DEBUG] client.alloc_watcher: deadline for alloc "12f136bf-380d-d659-6f87-0c2769488bf8" is at 2018-04-26 05:45:59.485409654 +0000 UTC m=+313.201320302 (deploy=false checks=true) 2018/04/26 05:40:59.485496 [DEBUG] client: starting task context for 'test4-0' (alloc '12f136bf-380d-d659-6f87-0c2769488bf8') 2018/04/26 05:40:59 [INFO] (runner) creating new runner (dry: false, once: false) 2018/04/26 05:40:59.500267 [DEBUG] client: updated allocations at index 27 (total 4) (pulled 1) (filtered 3) 2018/04/26 05:40:59.500376 [DEBUG] client: allocs: (added 1) (removed 0) (updated 0) (ignore 3) 2018/04/26 05:40:59 [DEBUG] (runner) final config: {"Consul":{"Address":"","Auth":{"Enabled":false,"Username":"","Password":""},"Retry":{"Attempts":12,"Backoff":250000000,"MaxBackoff":60000000000,"Enabled":true},"SSL":{"CaCert":"","CaPath":"","Cert":"","Enabled":false,"Key":"","ServerName":"","Verify":true},"Token":"","Transport":{"DialKeepAlive":30000000000,"DialTimeout":30000000000,"DisableKeepAlives":false,"IdleConnTimeout":90000000000,"MaxIdleConns":100,"MaxIdleConnsPerHost":5,"TLSHandshakeTimeout":10000000000}},"Dedup":{"Enabled":false,"MaxStale":2000000000,"Prefix":"consul-template/dedup/","TTL":15000000000},"Exec":{"Command":"","Enabled":false,"Env":{"Blacklist":[],"Custom":[],"Pristine":false,"Whitelist":[]},"KillSignal":2,"KillTimeout":30000000000,"ReloadSignal":null,"Splay":0,"Timeout":0},"KillSignal":2,"LogLevel":"WARN","MaxStale":2000000000,"PidFile":"","ReloadSignal":1,"Syslog":{"Enabled":false,"Facility":"LOCAL0"},"Templates":[{"Backup":false,"Command":"","CommandTimeout":30000000000,"Contents":"#!/bin/sh\n\nset -e\nset -x\n\nCORE_RELEASE_FILE=\"heron-core.tar.gz\"\nCORE_RELEASE_DIR=\"heron-core\"\nTOPOLOGY_PACKAGE_FILE=\"topology.tar.gz\"\n\n# Create working directory if it does not exist\nmkdir -p ${HERON_NOMAD_WORKING_DIR}\n\n# Go to working directory\ncd ${HERON_NOMAD_WORKING_DIR}\n\nif [ \"$HERON_USE_CORE_PACKAGE_URI\" == \"true\" ]; then\n # download and extract heron core package\n curl ${HERON_CORE_PACKAGE_URI} -o ${CORE_RELEASE_FILE}\n tar zxf ${CORE_RELEASE_FILE} \u0026\u0026 rm -rf ${CORE_RELEASE_FILE}\nelse\n # link the heron core package directory\n ln -s ${HERON_CORE_PACKAGE_DIR} ${CORE_RELEASE_DIR}\nfi\n\n# download and extract heron topology package\n${HERON_TOPOLOGY_DOWNLOAD_CMD}\n\n# launch heron executor\ntrap 'kill -TERM $PID' TERM INT\n${HERON_EXECUTOR_CMD} \u0026\nPID=$!\nwait $PID\ntrap - TERM INT\nwait $PID\nEXIT_STATUS=$?\n","CreateDestDirs":true,"Destination":"/tmp/slave/alloc/12f136bf-380d-d659-6f87-0c2769488bf8/test4-0/run_heron_executor.sh","ErrMissingKey":false,"Exec":{"Command":"","Enabled":false,"Env":{"Blacklist":[],"Custom":[],"Pristine":false,"Whitelist":[]},"KillSignal":2,"KillTimeout":30000000000,"ReloadSignal":null,"Splay":0,"Timeout":30000000000},"Perms":420,"Source":"","Wait":{"Enabled":false,"Min":0,"Max":0},"LeftDelim":"{{","RightDelim":"}}"}],"Vault":{"Address":"","Enabled":false,"Grace":15000000000,"RenewToken":false,"Retry":{"Attempts":12,"Backoff":250000000,"MaxBackoff":60000000000,"Enabled":true},"SSL":{"CaCert":"","CaPath":"","Cert":"","Enabled":true,"Key":"","ServerName":"","Verify":true},"Transport":{"DialKeepAlive":30000000000,"DialTimeout":30000000000,"DisableKeepAlives":false,"IdleConnTimeout":90000000000,"MaxIdleConns":100,"MaxIdleConnsPerHost":5,"TLSHandshakeTimeout":10000000000},"UnwrapToken":false},"Wait":{"Enabled":false,"Min":0,"Max":0}} 2018/04/26 05:40:59 [INFO] (runner) creating watcher 2018/04/26 05:40:59 [INFO] (runner) starting 2018/04/26 05:40:59 [DEBUG] (runner) running initial templates 2018/04/26 05:40:59 [INFO] (runner) initiating run 2018/04/26 05:40:59 [DEBUG] (runner) checking template d1abdd0b6726b6cc862162f577eba6b1 2018/04/26 05:40:59 [DEBUG] (runner) rendering "(dynamic)" => "/tmp/slave/alloc/12f136bf-380d-d659-6f87-0c2769488bf8/test4-0/run_heron_executor.sh" 2018/04/26 05:40:59 [INFO] (runner) rendered "(dynamic)" => "/tmp/slave/alloc/12f136bf-380d-d659-6f87-0c2769488bf8/test4-0/run_heron_executor.sh" 2018/04/26 05:40:59 [DEBUG] (runner) diffing and updating dependencies 2018/04/26 05:40:59 [DEBUG] (runner) watching 0 dependencies 2018/04/26 05:40:59 [DEBUG] (runner) all templates rendered 2018/04/26 05:40:59.505091 [DEBUG] client: unblocking task test4-0 for alloc "12f136bf-380d-d659-6f87-0c2769488bf8": Template 2018-04-26T05:40:59.505Z [DEBUG] plugin: starting plugin: path=/home/jerrypeng/.heron/bin/heron-nomad args="[/home/jerrypeng/.heron/bin/heron-nomad executor {"LogFile":"/tmp/slave/alloc/12f136bf-380d-d659-6f87-0c2769488bf8/test4-0/executor.out","LogLevel":"DEBUG"}]" 2018-04-26T05:40:59.507Z [DEBUG] plugin: waiting for RPC address: path=/home/jerrypeng/.heron/bin/heron-nomad 2018/04/26 05:40:59.514907 [DEBUG] client: starting task runners for alloc '53decbaf-e1ff-6739-35cc-7dc13dcd40e8' 2018/04/26 05:40:59.515152 [DEBUG] client: starting task context for 'test4-1' (alloc '53decbaf-e1ff-6739-35cc-7dc13dcd40e8') 2018/04/26 05:40:59.515380 [DEBUG] client.alloc_watcher: deadline for alloc "53decbaf-e1ff-6739-35cc-7dc13dcd40e8" is at 2018-04-26 05:45:59.515360241 +0000 UTC m=+313.231270864 (deploy=false checks=true) 2018/04/26 05:40:59.515938 [DEBUG] client: updated allocations at index 31 (total 5) (pulled 1) (filtered 4) 2018/04/26 05:40:59.516063 [DEBUG] client: allocs: (added 1) (removed 0) (updated 0) (ignore 4) 2018-04-26T05:40:59.526Z [DEBUG] plugin.heron-nomad: plugin address: timestamp=2018-04-26T05:40:59.526Z address=/tmp/plugin016378894 network=unix 2018/04/26 05:40:59 [INFO] (runner) creating new runner (dry: false, once: false) 2018/04/26 05:40:59 [DEBUG] (runner) final config: {"Consul":{"Address":"","Auth":{"Enabled":false,"Username":"","Password":""},"Retry":{"Attempts":12,"Backoff":250000000,"MaxBackoff":60000000000,"Enabled":true},"SSL":{"CaCert":"","CaPath":"","Cert":"","Enabled":false,"Key":"","ServerName":"","Verify":true},"Token":"","Transport":{"DialKeepAlive":30000000000,"DialTimeout":30000000000,"DisableKeepAlives":false,"IdleConnTimeout":90000000000,"MaxIdleConns":100,"MaxIdleConnsPerHost":5,"TLSHandshakeTimeout":10000000000}},"Dedup":{"Enabled":false,"MaxStale":2000000000,"Prefix":"consul-template/dedup/","TTL":15000000000},"Exec":{"Command":"","Enabled":false,"Env":{"Blacklist":[],"Custom":[],"Pristine":false,"Whitelist":[]},"KillSignal":2,"KillTimeout":30000000000,"ReloadSignal":null,"Splay":0,"Timeout":0},"KillSignal":2,"LogLevel":"WARN","MaxStale":2000000000,"PidFile":"","ReloadSignal":1,"Syslog":{"Enabled":false,"Facility":"LOCAL0"},"Templates":[{"Backup":false,"Command":"","CommandTimeout":30000000000,"Contents":"#!/bin/sh\n\nset -e\nset -x\n\nCORE_RELEASE_FILE=\"heron-core.tar.gz\"\nCORE_RELEASE_DIR=\"heron-core\"\nTOPOLOGY_PACKAGE_FILE=\"topology.tar.gz\"\n\n# Create working directory if it does not exist\nmkdir -p ${HERON_NOMAD_WORKING_DIR}\n\n# Go to working directory\ncd ${HERON_NOMAD_WORKING_DIR}\n\nif [ \"$HERON_USE_CORE_PACKAGE_URI\" == \"true\" ]; then\n # download and extract heron core package\n curl ${HERON_CORE_PACKAGE_URI} -o ${CORE_RELEASE_FILE}\n tar zxf ${CORE_RELEASE_FILE} \u0026\u0026 rm -rf ${CORE_RELEASE_FILE}\nelse\n # link the heron core package directory\n ln -s ${HERON_CORE_PACKAGE_DIR} ${CORE_RELEASE_DIR}\nfi\n\n# download and extract heron topology package\n${HERON_TOPOLOGY_DOWNLOAD_CMD}\n\n# launch heron executor\ntrap 'kill -TERM $PID' TERM INT\n${HERON_EXECUTOR_CMD} \u0026\nPID=$!\nwait $PID\ntrap - TERM INT\nwait $PID\nEXIT_STATUS=$?\n","CreateDestDirs":true,"Destination":"/tmp/slave/alloc/53decbaf-e1ff-6739-35cc-7dc13dcd40e8/test4-1/run_heron_executor.sh","ErrMissingKey":false,"Exec":{"Command":"","Enabled":false,"Env":{"Blacklist":[],"Custom":[],"Pristine":false,"Whitelist":[]},"KillSignal":2,"KillTimeout":30000000000,"ReloadSignal":null,"Splay":0,"Timeout":30000000000},"Perms":420,"Source":"","Wait":{"Enabled":false,"Min":0,"Max":0},"LeftDelim":"{{","RightDelim":"}}"}],"Vault":{"Address":"","Enabled":false,"Grace":15000000000,"RenewToken":false,"Retry":{"Attempts":12,"Backoff":250000000,"MaxBackoff":60000000000,"Enabled":true},"SSL":{"CaCert":"","CaPath":"","Cert":"","Enabled":true,"Key":"","ServerName":"","Verify":true},"Transport":{"DialKeepAlive":30000000000,"DialTimeout":30000000000,"DisableKeepAlives":false,"IdleConnTimeout":90000000000,"MaxIdleConns":100,"MaxIdleConnsPerHost":5,"TLSHandshakeTimeout":10000000000},"UnwrapToken":false},"Wait":{"Enabled":false,"Min":0,"Max":0}} 2018/04/26 05:40:59 [INFO] (runner) creating watcher 2018/04/26 05:40:59 [INFO] (runner) starting 2018/04/26 05:40:59 [DEBUG] (runner) running initial templates 2018/04/26 05:40:59 [INFO] (runner) initiating run 2018/04/26 05:40:59 [DEBUG] (runner) checking template d1abdd0b6726b6cc862162f577eba6b1 2018/04/26 05:40:59 [DEBUG] (runner) rendering "(dynamic)" => "/tmp/slave/alloc/53decbaf-e1ff-6739-35cc-7dc13dcd40e8/test4-1/run_heron_executor.sh" 2018/04/26 05:40:59.535056 [DEBUG] client: starting task runners for alloc 'f43c6e65-1f00-867f-a0d5-dba50e33a2d4' 2018/04/26 05:40:59.535407 [DEBUG] client.alloc_watcher: deadline for alloc "f43c6e65-1f00-867f-a0d5-dba50e33a2d4" is at 2018-04-26 05:45:59.535376266 +0000 UTC m=+313.251286894 (deploy=false checks=true) 2018/04/26 05:40:59.535528 [DEBUG] client: starting task context for 'test4-2' (alloc 'f43c6e65-1f00-867f-a0d5-dba50e33a2d4') 2018/04/26 05:40:59 [INFO] (runner) rendered "(dynamic)" => "/tmp/slave/alloc/53decbaf-e1ff-6739-35cc-7dc13dcd40e8/test4-1/run_heron_executor.sh" 2018/04/26 05:40:59 [DEBUG] (runner) diffing and updating dependencies 2018/04/26 05:40:59 [DEBUG] (runner) watching 0 dependencies 2018/04/26 05:40:59 [DEBUG] (runner) all templates rendered 2018/04/26 05:40:59.538117 [DEBUG] client: unblocking task test4-1 for alloc "53decbaf-e1ff-6739-35cc-7dc13dcd40e8": Template 2018-04-26T05:40:59.538Z [DEBUG] plugin: starting plugin: path=/home/jerrypeng/.heron/bin/heron-nomad args="[/home/jerrypeng/.heron/bin/heron-nomad executor {"LogFile":"/tmp/slave/alloc/53decbaf-e1ff-6739-35cc-7dc13dcd40e8/test4-1/executor.out","LogLevel":"DEBUG"}]" 2018-04-26T05:40:59.538Z [DEBUG] plugin: waiting for RPC address: path=/home/jerrypeng/.heron/bin/heron-nomad 2018/04/26 05:40:59.540227 [DEBUG] driver.raw_exec: started process with pid: 28324 2018/04/26 05:40:59 [INFO] (runner) creating new runner (dry: false, once: false) 2018/04/26 05:40:59 [DEBUG] (runner) final config: {"Consul":{"Address":"","Auth":{"Enabled":false,"Username":"","Password":""},"Retry":{"Attempts":12,"Backoff":250000000,"MaxBackoff":60000000000,"Enabled":true},"SSL":{"CaCert":"","CaPath":"","Cert":"","Enabled":false,"Key":"","ServerName":"","Verify":true},"Token":"","Transport":{"DialKeepAlive":30000000000,"DialTimeout":30000000000,"DisableKeepAlives":false,"IdleConnTimeout":90000000000,"MaxIdleConns":100,"MaxIdleConnsPerHost":5,"TLSHandshakeTimeout":10000000000}},"Dedup":{"Enabled":false,"MaxStale":2000000000,"Prefix":"consul-template/dedup/","TTL":15000000000},"Exec":{"Command":"","Enabled":false,"Env":{"Blacklist":[],"Custom":[],"Pristine":false,"Whitelist":[]},"KillSignal":2,"KillTimeout":30000000000,"ReloadSignal":null,"Splay":0,"Timeout":0},"KillSignal":2,"LogLevel":"WARN","MaxStale":2000000000,"PidFile":"","ReloadSignal":1,"Syslog":{"Enabled":false,"Facility":"LOCAL0"},"Templates":[{"Backup":false,"Command":"","CommandTimeout":30000000000,"Contents":"#!/bin/sh\n\nset -e\nset -x\n\nCORE_RELEASE_FILE=\"heron-core.tar.gz\"\nCORE_RELEASE_DIR=\"heron-core\"\nTOPOLOGY_PACKAGE_FILE=\"topology.tar.gz\"\n\n# Create working directory if it does not exist\nmkdir -p ${HERON_NOMAD_WORKING_DIR}\n\n# Go to working directory\ncd ${HERON_NOMAD_WORKING_DIR}\n\nif [ \"$HERON_USE_CORE_PACKAGE_URI\" == \"true\" ]; then\n # download and extract heron core package\n curl ${HERON_CORE_PACKAGE_URI} -o ${CORE_RELEASE_FILE}\n tar zxf ${CORE_RELEASE_FILE} \u0026\u0026 rm -rf ${CORE_RELEASE_FILE}\nelse\n # link the heron core package directory\n ln -s ${HERON_CORE_PACKAGE_DIR} ${CORE_RELEASE_DIR}\nfi\n\n# download and extract heron topology package\n${HERON_TOPOLOGY_DOWNLOAD_CMD}\n\n# launch heron executor\ntrap 'kill -TERM $PID' TERM INT\n${HERON_EXECUTOR_CMD} \u0026\nPID=$!\nwait $PID\ntrap - TERM INT\nwait $PID\nEXIT_STATUS=$?\n","CreateDestDirs":true,"Destination":"/tmp/slave/alloc/f43c6e65-1f00-867f-a0d5-dba50e33a2d4/test4-2/run_heron_executor.sh","ErrMissingKey":false,"Exec":{"Command":"","Enabled":false,"Env":{"Blacklist":[],"Custom":[],"Pristine":false,"Whitelist":[]},"KillSignal":2,"KillTimeout":30000000000,"ReloadSignal":null,"Splay":0,"Timeout":30000000000},"Perms":420,"Source":"","Wait":{"Enabled":false,"Min":0,"Max":0},"LeftDelim":"{{","RightDelim":"}}"}],"Vault":{"Address":"","Enabled":false,"Grace":15000000000,"RenewToken":false,"Retry":{"Attempts":12,"Backoff":250000000,"MaxBackoff":60000000000,"Enabled":true},"SSL":{"CaCert":"","CaPath":"","Cert":"","Enabled":true,"Key":"","ServerName":"","Verify":true},"Transport":{"DialKeepAlive":30000000000,"DialTimeout":30000000000,"DisableKeepAlives":false,"IdleConnTimeout":90000000000,"MaxIdleConns":100,"MaxIdleConnsPerHost":5,"TLSHandshakeTimeout":10000000000},"UnwrapToken":false},"Wait":{"Enabled":false,"Min":0,"Max":0}} 2018/04/26 05:40:59 [INFO] (runner) creating watcher 2018/04/26 05:40:59 [INFO] (runner) starting 2018/04/26 05:40:59 [DEBUG] (runner) running initial templates 2018/04/26 05:40:59 [INFO] (runner) initiating run 2018/04/26 05:40:59 [DEBUG] (runner) checking template d1abdd0b6726b6cc862162f577eba6b1 2018/04/26 05:40:59 [DEBUG] (runner) rendering "(dynamic)" => "/tmp/slave/alloc/f43c6e65-1f00-867f-a0d5-dba50e33a2d4/test4-2/run_heron_executor.sh" 2018-04-26T05:40:59.558Z [DEBUG] plugin.heron-nomad: plugin address: timestamp=2018-04-26T05:40:59.558Z address=/tmp/plugin404627412 network=unix 2018/04/26 05:40:59 [INFO] (runner) rendered "(dynamic)" => "/tmp/slave/alloc/f43c6e65-1f00-867f-a0d5-dba50e33a2d4/test4-2/run_heron_executor.sh" 2018/04/26 05:40:59 [DEBUG] (runner) diffing and updating dependencies 2018/04/26 05:40:59 [DEBUG] (runner) watching 0 dependencies 2018/04/26 05:40:59 [DEBUG] (runner) all templates rendered 2018/04/26 05:40:59.559102 [DEBUG] client: unblocking task test4-2 for alloc "f43c6e65-1f00-867f-a0d5-dba50e33a2d4": Template 2018-04-26T05:40:59.559Z [DEBUG] plugin: starting plugin: path=/home/jerrypeng/.heron/bin/heron-nomad args="[/home/jerrypeng/.heron/bin/heron-nomad executor {"LogFile":"/tmp/slave/alloc/f43c6e65-1f00-867f-a0d5-dba50e33a2d4/test4-2/executor.out","LogLevel":"DEBUG"}]" 2018-04-26T05:40:59.559Z [DEBUG] plugin: waiting for RPC address: path=/home/jerrypeng/.heron/bin/heron-nomad 2018/04/26 05:40:59.570413 [DEBUG] driver.raw_exec: started process with pid: 28350 2018-04-26T05:40:59.581Z [DEBUG] plugin.heron-nomad: plugin address: timestamp=2018-04-26T05:40:59.581Z address=/tmp/plugin128744274 network=unix 2018/04/26 05:40:59.603377 [DEBUG] driver.raw_exec: started process with pid: 28365 2018/04/26 05:40:59.627820 [DEBUG] client: updated allocations at index 41 (total 5) (pulled 0) (filtered 5) 2018/04/26 05:40:59.628016 [DEBUG] client: allocs: (added 0) (removed 0) (updated 0) (ignore 5) 2018/04/26 05:40:59.818896 [DEBUG] client: updated allocations at index 42 (total 5) (pulled 0) (filtered 5) 2018/04/26 05:40:59.819075 [DEBUG] client: allocs: (added 0) (removed 0) (updated 0) (ignore 5) 2018/04/26 05:41:00.415966 [DEBUG] client: updated allocations at index 43 (total 5) (pulled 0) (filtered 5) 2018/04/26 05:41:00.416241 [DEBUG] client: allocs: (added 0) (removed 0) (updated 0) (ignore 5) 2018/04/26 05:41:01.020514 [DEBUG] client: updated allocations at index 44 (total 5) (pulled 0) (filtered 5) 2018/04/26 05:41:01.020664 [DEBUG] client: allocs: (added 0) (removed 0) (updated 0) (ignore 5) 2018/04/26 05:41:09.616421 [DEBUG] client: updated allocations at index 45 (total 5) (pulled 0) (filtered 5) 2018/04/26 05:41:09.616546 [DEBUG] client: allocs: (added 0) (removed 0) (updated 0) (ignore 5) 2018/04/26 05:41:09.815803 [DEBUG] client: updated allocations at index 46 (total 5) (pulled 0) (filtered 5) 2018/04/26 05:41:09.815884 [DEBUG] client: allocs: (added 0) (removed 0) (updated 0) (ignore 5) 2018/04/26 05:41:26.539516 [DEBUG] client: updated allocations at index 49 (total 5) (pulled 1) (filtered 4) 2018/04/26 05:41:26.539662 [DEBUG] client: allocs: (added 0) (removed 0) (updated 1) (ignore 4) 2018/04/26 05:41:26.555452 [DEBUG] client: updated allocations at index 53 (total 5) (pulled 1) (filtered 4) 2018/04/26 05:41:26.555553 [DEBUG] client: allocs: (added 0) (removed 0) (updated 1) (ignore 4) 2018/04/26 05:41:26.567572 [DEBUG] client: updated allocations at index 57 (total 5) (pulled 1) (filtered 4) 2018/04/26 05:41:26.567689 [DEBUG] client: allocs: (added 0) (removed 0) (updated 1) (ignore 4) 2018/04/26 05:41:26.616477 [DEBUG] client: updated allocations at index 65 (total 5) (pulled 0) (filtered 5) 2018/04/26 05:41:26.616680 [DEBUG] client: allocs: (added 0) (removed 0) (updated 0) (ignore 5) 2018/04/26 05:41:26.823420 [DEBUG] client: updated allocations at index 66 (total 5) (pulled 0) (filtered 5) 2018/04/26 05:41:26.823562 [DEBUG] client: allocs: (added 0) (removed 0) (updated 0) (ignore 5) 2018/04/26 05:46:42.810538 [DEBUG] client: updated allocations at index 66 (total 5) (pulled 0) (filtered 5) 2018/04/26 05:46:42.810740 [DEBUG] client: allocs: (added 0) (removed 0) (updated 0) (ignore 5)