See
<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4243/display/redirect?page=changes>
Changes:
[jpeach] Fixed invalid handle bug in `os::process()`.
------------------------------------------
[...truncated 24.31 MB...]
3: I0920 00:49:57.373654 15882 authenticatee.cpp:259] Received SASL
authentication step
3: I0920 00:49:57.373782 15874 authenticator.cpp:232] Received SASL
authentication step
3: I0920 00:49:57.373811 15874 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '9e396e7980ec' server FQDN: '9e396e7980ec'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
3: I0920 00:49:57.373828 15874 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
3: I0920 00:49:57.373869 15874 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
3: I0920 00:49:57.373894 15874 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '9e396e7980ec' server FQDN: '9e396e7980ec'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
3: I0920 00:49:57.373906 15874 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I0920 00:49:57.373911 15874 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I0920 00:49:57.373924 15874 authenticator.cpp:318] Authentication success
3: I0920 00:49:57.373988 15890 authenticatee.cpp:299] Authentication success
3: I0920 00:49:57.374073 15889 master.cpp:7925] Successfully authenticated
principal 'test-principal' at slave(635)@172.17.0.3:40743
3: I0920 00:49:57.374100 15883 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(1254)@172.17.0.3:40743
3: I0920 00:49:57.374326 15890 slave.cpp:1157] Successfully authenticated with
master master@172.17.0.3:40743
3: I0920 00:49:57.374572 15890 slave.cpp:1636] Will retry registration in
12.97271ms if necessary
3: I0920 00:49:57.374761 15888 master.cpp:5769] Received register agent message
from slave(635)@172.17.0.3:40743 (9e396e7980ec)
3: I0920 00:49:57.374794 15888 master.cpp:3806] Authorizing agent with
principal 'test-principal'
3: I0920 00:49:57.375190 15894 master.cpp:5829] Authorized registration of
agent at slave(635)@172.17.0.3:40743 (9e396e7980ec)
3: I0920 00:49:57.375290 15894 master.cpp:5922] Registering agent at
slave(635)@172.17.0.3:40743 (9e396e7980ec) with id
c12e0a18-0a26-4d55-891a-a97cc788b817-S0
3: I0920 00:49:57.375622 15877 registrar.cpp:495] Applied 1 operations in
73129ns; attempting to update the registry
3: I0920 00:49:57.376191 15877 registrar.cpp:552] Successfully updated the
registry in 503040ns
3: I0920 00:49:57.376379 15891 master.cpp:5969] Admitted agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0 at slave(635)@172.17.0.3:40743
(9e396e7980ec)
3: I0920 00:49:57.376910 15878 slave.cpp:4976] Received ping from
slave-observer(606)@172.17.0.3:40743
3: I0920 00:49:57.377125 15878 slave.cpp:1203] Registered with master
master@172.17.0.3:40743; given agent ID c12e0a18-0a26-4d55-891a-a97cc788b817-S0
3: I0920 00:49:57.377197 15875 status_update_manager.cpp:184] Resuming sending
status updates
3: I0920 00:49:57.376966 15891 master.cpp:6000] Registered agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0 at slave(635)@172.17.0.3:40743
(9e396e7980ec) with
[{"name":"disk","reservations":[{"role":"role1","type":"STATIC"}],"scalar":{"value":1024.0},"type":"SCALAR"},{"name":"cpus","scalar":{"value":24.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":95614.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I0920 00:49:57.377452 15878 slave.cpp:1223] Checkpointing SlaveInfo to
'/tmp/ContentType_MasterAPITest_CreateAndDestroyVolumes_1_xpWkI0/meta/slaves/c12e0a18-0a26-4d55-891a-a97cc788b817-S0/slave.info'
3: I0920 00:49:57.377454 15880 hierarchical.cpp:593] Added agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0 (9e396e7980ec) with disk(reservations:
[(STATIC,role1)]):1024; cpus:24; mem:95614; ports:[31000-32000] (allocated: {})
3: I0920 00:49:57.377704 15880 hierarchical.cpp:1943] No allocations performed
3: I0920 00:49:57.377746 15880 hierarchical.cpp:1486] Performed allocation for
1 agents in 163379ns
3: I0920 00:49:57.377799 15878 slave.cpp:1272] Forwarding total oversubscribed
resources {}
3: I0920 00:49:57.377938 15881 master.cpp:6746] Received update of agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0 at slave(635)@172.17.0.3:40743
(9e396e7980ec) with total oversubscribed resources {}
3: I0920 00:49:57.378216 15893 hierarchical.cpp:660] Agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0 (9e396e7980ec) updated with total
resources disk(reservations: [(STATIC,role1)]):1024; cpus:24; mem:95614;
ports:[31000-32000]
3: I0920 00:49:57.379918 15876 process.cpp:3929] Handling HTTP event for
process 'master' with path: '/master/api/v1'
3: I0920 00:49:57.380937 15887 http.cpp:1185] HTTP POST for /master/api/v1 from
172.17.0.3:35196
3: I0920 00:49:57.381489 15887 http.cpp:673] Processing call CREATE_VOLUMES
3: I0920 00:49:57.381847 15887 master.cpp:3726] Authorizing principal
'test-principal' to create volumes
'[{"disk":{"persistence":{"id":"id1","principal":"test-principal"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"role1","type":"STATIC"}],"scalar":{"value":64.0},"type":"SCALAR"}]'
3: I0920 00:49:57.384171 15891 master.cpp:9171] Sending updated checkpointed
resources disk(reservations: [(STATIC,role1)])[id1:path1]:64 to agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0 at slave(635)@172.17.0.3:40743
(9e396e7980ec)
3: I0920 00:49:57.385345 15878 slave.cpp:3529] Updated checkpointed resources
from {} to disk(reservations: [(STATIC,role1)])[id1:path1]:64
3: W0920 00:49:57.385517 15873 process.cpp:3194] Attempted to spawn already
running process version@172.17.0.3:40743
3: I0920 00:49:57.386334 15873 sched.cpp:232] Version: 1.5.0
3: I0920 00:49:57.386848 15879 sched.cpp:336] New master detected at
master@172.17.0.3:40743
3: I0920 00:49:57.386922 15879 sched.cpp:407] Authenticating with master
master@172.17.0.3:40743
3: I0920 00:49:57.386941 15879 sched.cpp:414] Using default CRAM-MD5
authenticatee
3: I0920 00:49:57.387164 15882 authenticatee.cpp:121] Creating new client SASL
connection
3: I0920 00:49:57.387398 15875 master.cpp:7895] Authenticating
scheduler-687e3d07-cfaa-4db2-8913-2c02e9348038@172.17.0.3:40743
3: I0920 00:49:57.387490 15897 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(1255)@172.17.0.3:40743
3: I0920 00:49:57.387689 15884 authenticator.cpp:98] Creating new server SASL
connection
3: I0920 00:49:57.387861 15874 authenticatee.cpp:213] Received SASL
authentication mechanisms: CRAM-MD5
3: I0920 00:49:57.387883 15874 authenticatee.cpp:239] Attempting to
authenticate with mechanism 'CRAM-MD5'
3: I0920 00:49:57.387974 15880 authenticator.cpp:204] Received SASL
authentication start
3: I0920 00:49:57.388025 15880 authenticator.cpp:326] Authentication requires
more steps
3: I0920 00:49:57.388108 15891 authenticatee.cpp:259] Received SASL
authentication step
3: I0920 00:49:57.388206 15881 authenticator.cpp:232] Received SASL
authentication step
3: I0920 00:49:57.388229 15881 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '9e396e7980ec' server FQDN: '9e396e7980ec'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
3: I0920 00:49:57.388242 15881 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
3: I0920 00:49:57.388286 15881 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
3: I0920 00:49:57.388305 15881 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '9e396e7980ec' server FQDN: '9e396e7980ec'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
3: I0920 00:49:57.388315 15881 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I0920 00:49:57.388320 15881 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I0920 00:49:57.388332 15881 authenticator.cpp:318] Authentication success
3: I0920 00:49:57.388397 15893 authenticatee.cpp:299] Authentication success
3: I0920 00:49:57.388458 15883 master.cpp:7925] Successfully authenticated
principal 'test-principal' at
scheduler-687e3d07-cfaa-4db2-8913-2c02e9348038@172.17.0.3:40743
3: I0920 00:49:57.388515 15890 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(1255)@172.17.0.3:40743
3: I0920 00:49:57.388614 15895 sched.cpp:513] Successfully authenticated with
master master@172.17.0.3:40743
3: I0920 00:49:57.388630 15895 sched.cpp:836] Sending SUBSCRIBE call to
master@172.17.0.3:40743
3: I0920 00:49:57.388751 15895 sched.cpp:869] Will retry registration in
214.597081ms if necessary
3: I0920 00:49:57.388895 15876 master.cpp:2897] Received SUBSCRIBE call for
framework 'default' at
scheduler-687e3d07-cfaa-4db2-8913-2c02e9348038@172.17.0.3:40743
3: I0920 00:49:57.388916 15876 master.cpp:2231] Authorizing framework principal
'test-principal' to receive offers for roles '{ role1 }'
3: I0920 00:49:57.389334 15886 master.cpp:2977] Subscribing framework default
with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I0920 00:49:57.389813 15892 sched.cpp:759] Framework registered with
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.389863 15892 sched.cpp:773] Scheduler::registered took 34978ns
3: I0920 00:49:57.390007 15885 hierarchical.cpp:303] Added framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.391432 15885 hierarchical.cpp:2033] No inverse offers to send
out!
3: I0920 00:49:57.391471 15885 hierarchical.cpp:1486] Performed allocation for
1 agents in 1.327158ms
3: I0920 00:49:57.392099 15882 master.cpp:7725] Sending 1 offers to framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000 (default) at
scheduler-687e3d07-cfaa-4db2-8913-2c02e9348038@172.17.0.3:40743
3: I0920 00:49:57.392611 15875 sched.cpp:933] Scheduler::resourceOffers took
105858ns
3: I0920 00:49:57.394589 15883 master.cpp:9225] Removing offer
c12e0a18-0a26-4d55-891a-a97cc788b817-O0
3: I0920 00:49:57.394719 15883 master.cpp:4164] Processing ACCEPT call for
offers: [ c12e0a18-0a26-4d55-891a-a97cc788b817-O0 ] on agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0 at slave(635)@172.17.0.3:40743
(9e396e7980ec) for framework c12e0a18-0a26-4d55-891a-a97cc788b817-0000
(default) at scheduler-687e3d07-cfaa-4db2-8913-2c02e9348038@172.17.0.3:40743
3: I0920 00:49:57.394793 15883 master.cpp:3533] Authorizing framework principal
'test-principal' to launch task 1429b020-4484-4d6e-9991-291e87e7f2f4
3: W0920 00:49:57.396271 15883 validation.cpp:1332] Executor 'default' for task
'1429b020-4484-4d6e-9991-291e87e7f2f4' uses less CPUs (None) than the minimum
required (0.01). Please update your executor, as this will be mandatory in
future releases.
3: W0920 00:49:57.396296 15883 validation.cpp:1344] Executor 'default' for task
'1429b020-4484-4d6e-9991-291e87e7f2f4' uses less memory (None) than the minimum
required (32MB). Please update your executor, as this will be mandatory in
future releases.
3: I0920 00:49:57.396630 15883 master.cpp:9971] Adding task
1429b020-4484-4d6e-9991-291e87e7f2f4 with resources
[{"allocation_info":{"role":"role1"},"name":"disk","reservations":[{"role":"role1","type":"STATIC"}],"scalar":{"value":256.0},"type":"SCALAR"}]
on agent c12e0a18-0a26-4d55-891a-a97cc788b817-S0 at
slave(635)@172.17.0.3:40743 (9e396e7980ec)
3: I0920 00:49:57.396955 15883 master.cpp:4847] Launching task
1429b020-4484-4d6e-9991-291e87e7f2f4 of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000 (default) at
scheduler-687e3d07-cfaa-4db2-8913-2c02e9348038@172.17.0.3:40743 with resources
[{"allocation_info":{"role":"role1"},"name":"disk","reservations":[{"role":"role1","type":"STATIC"}],"scalar":{"value":256.0},"type":"SCALAR"}]
on agent c12e0a18-0a26-4d55-891a-a97cc788b817-S0 at
slave(635)@172.17.0.3:40743 (9e396e7980ec)
3: I0920 00:49:57.397353 15890 slave.cpp:1757] Got assigned task
'1429b020-4484-4d6e-9991-291e87e7f2f4' for framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.397943 15890 slave.cpp:2025] Authorizing task
'1429b020-4484-4d6e-9991-291e87e7f2f4' for framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.397989 15890 slave.cpp:6819] Authorizing framework principal
'test-principal' to launch task 1429b020-4484-4d6e-9991-291e87e7f2f4
3: I0920 00:49:57.398507 15886 slave.cpp:2193] Launching task
'1429b020-4484-4d6e-9991-291e87e7f2f4' for framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.399027 15886 paths.cpp:594] Trying to chown
'/tmp/ContentType_MasterAPITest_CreateAndDestroyVolumes_1_xpWkI0/slaves/c12e0a18-0a26-4d55-891a-a97cc788b817-S0/frameworks/c12e0a18-0a26-4d55-891a-a97cc788b817-0000/executors/default/runs/f0be61b9-1f76-4fe5-86ce-7b2dfe60351b'
to user 'mesos'
3: I0920 00:49:57.399121 15895 hierarchical.cpp:887] Updated allocation of
framework c12e0a18-0a26-4d55-891a-a97cc788b817-0000 on agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0 from disk(allocated:
role1)(reservations: [(STATIC,role1)]):960; cpus(allocated: role1):24;
mem(allocated: role1):95614; ports(allocated: role1):[31000-32000];
disk(allocated: role1)(reservations: [(STATIC,role1)])[id1:path1]:64 to
disk(allocated: role1)(reservations: [(STATIC,role1)]):960; cpus(allocated:
role1):24; mem(allocated: role1):95614; ports(allocated: role1):[31000-32000];
disk(allocated: role1)(reservations: [(STATIC,role1)])[id1:path1]:64
3: I0920 00:49:57.399260 15886 slave.cpp:7281] Launching executor 'default' of
framework c12e0a18-0a26-4d55-891a-a97cc788b817-0000 with resources [] in work
directory
'/tmp/ContentType_MasterAPITest_CreateAndDestroyVolumes_1_xpWkI0/slaves/c12e0a18-0a26-4d55-891a-a97cc788b817-S0/frameworks/c12e0a18-0a26-4d55-891a-a97cc788b817-0000/executors/default/runs/f0be61b9-1f76-4fe5-86ce-7b2dfe60351b'
3: I0920 00:49:57.399628 15886 slave.cpp:2884] Launching container
f0be61b9-1f76-4fe5-86ce-7b2dfe60351b for executor 'default' of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.399875 15886 slave.cpp:2421] Queued task
'1429b020-4484-4d6e-9991-291e87e7f2f4' for executor 'default' of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.399942 15886 slave.cpp:951] Successfully attached
'/tmp/ContentType_MasterAPITest_CreateAndDestroyVolumes_1_xpWkI0/slaves/c12e0a18-0a26-4d55-891a-a97cc788b817-S0/frameworks/c12e0a18-0a26-4d55-891a-a97cc788b817-0000/executors/default/runs/f0be61b9-1f76-4fe5-86ce-7b2dfe60351b'
to virtual path
'/tmp/ContentType_MasterAPITest_CreateAndDestroyVolumes_1_xpWkI0/slaves/c12e0a18-0a26-4d55-891a-a97cc788b817-S0/frameworks/c12e0a18-0a26-4d55-891a-a97cc788b817-0000/executors/default/runs/latest'
3: I0920 00:49:57.399971 15886 slave.cpp:951] Successfully attached
'/tmp/ContentType_MasterAPITest_CreateAndDestroyVolumes_1_xpWkI0/slaves/c12e0a18-0a26-4d55-891a-a97cc788b817-S0/frameworks/c12e0a18-0a26-4d55-891a-a97cc788b817-0000/executors/default/runs/f0be61b9-1f76-4fe5-86ce-7b2dfe60351b'
to virtual path
'/frameworks/c12e0a18-0a26-4d55-891a-a97cc788b817-0000/executors/default/runs/latest'
3: I0920 00:49:57.399994 15886 slave.cpp:951] Successfully attached
'/tmp/ContentType_MasterAPITest_CreateAndDestroyVolumes_1_xpWkI0/slaves/c12e0a18-0a26-4d55-891a-a97cc788b817-S0/frameworks/c12e0a18-0a26-4d55-891a-a97cc788b817-0000/executors/default/runs/f0be61b9-1f76-4fe5-86ce-7b2dfe60351b'
to virtual path
'/tmp/ContentType_MasterAPITest_CreateAndDestroyVolumes_1_xpWkI0/slaves/c12e0a18-0a26-4d55-891a-a97cc788b817-S0/frameworks/c12e0a18-0a26-4d55-891a-a97cc788b817-0000/executors/default/runs/f0be61b9-1f76-4fe5-86ce-7b2dfe60351b'
3: I0920 00:49:57.400193 15895 hierarchical.cpp:1161] Recovered disk(allocated:
role1)(reservations: [(STATIC,role1)]):704; cpus(allocated: role1):24;
mem(allocated: role1):95614; ports(allocated: role1):[31000-32000];
disk(allocated: role1)(reservations: [(STATIC,role1)])[id1:path1]:64 (total:
disk(reservations: [(STATIC,role1)]):960; cpus:24; mem:95614;
ports:[31000-32000]; disk(reservations: [(STATIC,role1)])[id1:path1]:64,
allocated: disk(allocated: role1)(reservations: [(STATIC,role1)]):256) on agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0 from framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.400266 15895 hierarchical.cpp:1207] Framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000 filtered agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0 for 5secs
3: W0920 00:49:57.401975 15887 process.cpp:3194] Attempted to spawn already
running process version@172.17.0.3:40743
3: I0920 00:49:57.402134 15887 exec.cpp:162] Version: 1.5.0
3: I0920 00:49:57.402401 15882 exec.cpp:212] Executor started at:
executor(188)@172.17.0.3:40743 with pid 15873
3: I0920 00:49:57.402592 15875 slave.cpp:3951] Got registration for executor
'default' of framework c12e0a18-0a26-4d55-891a-a97cc788b817-0000 from
executor(188)@172.17.0.3:40743
3: I0920 00:49:57.402940 15874 exec.cpp:237] Executor registered on agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0
3: I0920 00:49:57.402977 15874 exec.cpp:249] Executor::registered took 24228ns
3: I0920 00:49:57.403344 15875 slave.cpp:2623] Sending queued task
'1429b020-4484-4d6e-9991-291e87e7f2f4' to executor 'default' of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000 at executor(188)@172.17.0.3:40743
3: I0920 00:49:57.403601 15880 exec.cpp:331] Executor asked to run task
'1429b020-4484-4d6e-9991-291e87e7f2f4'
3: I0920 00:49:57.403676 15880 exec.cpp:340] Executor::launchTask took 63281ns
3: I0920 00:49:57.403764 15880 exec.cpp:571] Executor sending status update
TASK_FINISHED (UUID: 55583be5-bc58-42d9-8145-fe1d439bf7bb) for task
1429b020-4484-4d6e-9991-291e87e7f2f4 of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.404014 15893 slave.cpp:4405] Handling status update
TASK_FINISHED (UUID: 55583be5-bc58-42d9-8145-fe1d439bf7bb) for task
1429b020-4484-4d6e-9991-291e87e7f2f4 of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000 from executor(188)@172.17.0.3:40743
3: I0920 00:49:57.404736 15888 status_update_manager.cpp:323] Received status
update TASK_FINISHED (UUID: 55583be5-bc58-42d9-8145-fe1d439bf7bb) for task
1429b020-4484-4d6e-9991-291e87e7f2f4 of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.404773 15888 status_update_manager.cpp:500] Creating
StatusUpdate stream for task 1429b020-4484-4d6e-9991-291e87e7f2f4 of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.405203 15888 status_update_manager.cpp:377] Forwarding update
TASK_FINISHED (UUID: 55583be5-bc58-42d9-8145-fe1d439bf7bb) for task
1429b020-4484-4d6e-9991-291e87e7f2f4 of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000 to the agent
3: I0920 00:49:57.405387 15878 slave.cpp:4886] Forwarding the update
TASK_FINISHED (UUID: 55583be5-bc58-42d9-8145-fe1d439bf7bb) for task
1429b020-4484-4d6e-9991-291e87e7f2f4 of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000 to master@172.17.0.3:40743
3: I0920 00:49:57.405575 15878 slave.cpp:4780] Status update manager
successfully handled status update TASK_FINISHED (UUID:
55583be5-bc58-42d9-8145-fe1d439bf7bb) for task
1429b020-4484-4d6e-9991-291e87e7f2f4 of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.405619 15878 slave.cpp:4796] Sending acknowledgement for
status update TASK_FINISHED (UUID: 55583be5-bc58-42d9-8145-fe1d439bf7bb) for
task 1429b020-4484-4d6e-9991-291e87e7f2f4 of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000 to executor(188)@172.17.0.3:40743
3: I0920 00:49:57.405730 15894 master.cpp:6904] Status update TASK_FINISHED
(UUID: 55583be5-bc58-42d9-8145-fe1d439bf7bb) for task
1429b020-4484-4d6e-9991-291e87e7f2f4 of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000 from agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0 at slave(635)@172.17.0.3:40743
(9e396e7980ec)
3: I0920 00:49:57.405778 15883 exec.cpp:388] Executor received status update
acknowledgement 55583be5-bc58-42d9-8145-fe1d439bf7bb for task
1429b020-4484-4d6e-9991-291e87e7f2f4 of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.405787 15894 master.cpp:6966] Forwarding status update
TASK_FINISHED (UUID: 55583be5-bc58-42d9-8145-fe1d439bf7bb) for task
1429b020-4484-4d6e-9991-291e87e7f2f4 of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.405953 15894 master.cpp:8993] Updating the state of task
1429b020-4484-4d6e-9991-291e87e7f2f4 of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000 (latest state: TASK_FINISHED, status
update state: TASK_FINISHED)
3: I0920 00:49:57.406157 15896 sched.cpp:1041] Scheduler::statusUpdate took
110679ns
3: I0920 00:49:57.406388 15877 hierarchical.cpp:1161] Recovered disk(allocated:
role1)(reservations: [(STATIC,role1)]):256 (total: disk(reservations:
[(STATIC,role1)]):960; cpus:24; mem:95614; ports:[31000-32000];
disk(reservations: [(STATIC,role1)])[id1:path1]:64, allocated: {}) on agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0 from framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.406457 15886 master.cpp:5534] Processing ACKNOWLEDGE call
55583be5-bc58-42d9-8145-fe1d439bf7bb for task
1429b020-4484-4d6e-9991-291e87e7f2f4 of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000 (default) at
scheduler-687e3d07-cfaa-4db2-8913-2c02e9348038@172.17.0.3:40743 on agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0
3: I0920 00:49:57.406507 15886 master.cpp:9087] Removing task
1429b020-4484-4d6e-9991-291e87e7f2f4 with resources
[{"allocation_info":{"role":"role1"},"name":"disk","reservations":[{"role":"role1","type":"STATIC"}],"scalar":{"value":256.0},"type":"SCALAR"}]
of framework c12e0a18-0a26-4d55-891a-a97cc788b817-0000 on agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0 at slave(635)@172.17.0.3:40743
(9e396e7980ec)
3: I0920 00:49:57.406908 15885 status_update_manager.cpp:395] Received status
update acknowledgement (UUID: 55583be5-bc58-42d9-8145-fe1d439bf7bb) for task
1429b020-4484-4d6e-9991-291e87e7f2f4 of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.407012 15885 status_update_manager.cpp:531] Cleaning up
status update stream for task 1429b020-4484-4d6e-9991-291e87e7f2f4 of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.407264 15882 slave.cpp:3689] Status update manager
successfully handled status update acknowledgement (UUID:
55583be5-bc58-42d9-8145-fe1d439bf7bb) for task
1429b020-4484-4d6e-9991-291e87e7f2f4 of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.407302 15882 slave.cpp:7886] Completing task
1429b020-4484-4d6e-9991-291e87e7f2f4
3: I0920 00:49:57.408866 15880 process.cpp:3929] Handling HTTP event for
process 'master' with path: '/master/api/v1'
3: I0920 00:49:57.409924 15883 http.cpp:1185] HTTP POST for /master/api/v1 from
172.17.0.3:35198
3: I0920 00:49:57.410449 15883 http.cpp:673] Processing call DESTROY_VOLUMES
3: I0920 00:49:57.410775 15883 master.cpp:3778] Authorizing principal
'test-principal' to destroy volumes
'[{"disk":{"persistence":{"id":"id1","principal":"test-principal"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"role1","type":"STATIC"}],"scalar":{"value":64.0},"type":"SCALAR"}]'
3: I0920 00:49:57.413022 15896 master.cpp:9171] Sending updated checkpointed
resources {} to agent c12e0a18-0a26-4d55-891a-a97cc788b817-S0 at
slave(635)@172.17.0.3:40743 (9e396e7980ec)
3: I0920 00:49:57.413504 15886 slave.cpp:3594] Deleting persistent volume 'id1'
at
'/tmp/ContentType_MasterAPITest_CreateAndDestroyVolumes_1_xpWkI0/volumes/roles/role1/id1'
3: I0920 00:49:57.413746 15886 slave.cpp:3529] Updated checkpointed resources
from disk(reservations: [(STATIC,role1)])[id1:path1]:64 to {}
3: I0920 00:49:57.413779 15873 sched.cpp:2021] Asked to stop the driver
3: I0920 00:49:57.413857 15875 sched.cpp:1203] Stopping framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.414079 15880 master.cpp:8406] Processing TEARDOWN call for
framework c12e0a18-0a26-4d55-891a-a97cc788b817-0000 (default) at
scheduler-687e3d07-cfaa-4db2-8913-2c02e9348038@172.17.0.3:40743
3: I0920 00:49:57.414108 15880 master.cpp:8418] Removing framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000 (default) at
scheduler-687e3d07-cfaa-4db2-8913-2c02e9348038@172.17.0.3:40743
3: I0920 00:49:57.414125 15880 master.cpp:3267] Deactivating framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000 (default) at
scheduler-687e3d07-cfaa-4db2-8913-2c02e9348038@172.17.0.3:40743
3: I0920 00:49:57.414247 15893 hierarchical.cpp:412] Deactivated framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.414289 15889 slave.cpp:3221] Asked to shut down framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000 by master@172.17.0.3:40743
3: I0920 00:49:57.414322 15889 slave.cpp:3246] Shutting down framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.414371 15889 slave.cpp:5753] Shutting down executor 'default'
of framework c12e0a18-0a26-4d55-891a-a97cc788b817-0000 at
executor(188)@172.17.0.3:40743
3: I0920 00:49:57.414376 15880 master.cpp:9116] Removing executor 'default'
with resources [] of framework c12e0a18-0a26-4d55-891a-a97cc788b817-0000 on
agent c12e0a18-0a26-4d55-891a-a97cc788b817-S0 at slave(635)@172.17.0.3:40743
(9e396e7980ec)
3: I0920 00:49:57.414461 15883 exec.cpp:435] Executor asked to shutdown
3: I0920 00:49:57.414495 15883 exec.cpp:450] Executor::shutdown took 20870ns
3: I0920 00:49:57.414625 15876 slave.cpp:5018] Got exited event for
executor(188)@172.17.0.3:40743
3: I0920 00:49:57.415020 15882 hierarchical.cpp:355] Removed framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: *** Aborted at 1505868597 (unix time) try "date -d @1505868597" if you are
using GNU date ***
3: I0920 00:49:57.415220 15894 slave.cpp:5418] Executor 'default' of framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000 exited with status 0
3: I0920 00:49:57.415302 15894 slave.cpp:5522] Cleaning up executor 'default'
of framework c12e0a18-0a26-4d55-891a-a97cc788b817-0000 at
executor(188)@172.17.0.3:40743
3: W0920 00:49:57.415422 15892 master.cpp:7021] Ignoring unknown exited
executor 'default' of framework c12e0a18-0a26-4d55-891a-a97cc788b817-0000 on
agent c12e0a18-0a26-4d55-891a-a97cc788b817-S0 at slave(635)@172.17.0.3:40743
(9e396e7980ec)
3: I0920 00:49:57.415482 15892 gc.cpp:91] Scheduling
'/tmp/ContentType_MasterAPITest_CreateAndDestroyVolumes_1_xpWkI0/slaves/c12e0a18-0a26-4d55-891a-a97cc788b817-S0/frameworks/c12e0a18-0a26-4d55-891a-a97cc788b817-0000/executors/default/runs/f0be61b9-1f76-4fe5-86ce-7b2dfe60351b'
for gc 6.99999519195556days in the future
3: I0920 00:49:57.415652 15887 gc.cpp:91] Scheduling
'/tmp/ContentType_MasterAPITest_CreateAndDestroyVolumes_1_xpWkI0/slaves/c12e0a18-0a26-4d55-891a-a97cc788b817-S0/frameworks/c12e0a18-0a26-4d55-891a-a97cc788b817-0000/executors/default'
for gc 6.99999518992days in the future
3: I0920 00:49:57.415658 15894 slave.cpp:5629] Cleaning up framework
c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.415735 15886 status_update_manager.cpp:285] Closing status
update streams for framework c12e0a18-0a26-4d55-891a-a97cc788b817-0000
3: I0920 00:49:57.415781 15886 gc.cpp:91] Scheduling
'/tmp/ContentType_MasterAPITest_CreateAndDestroyVolumes_1_xpWkI0/slaves/c12e0a18-0a26-4d55-891a-a97cc788b817-S0/frameworks/c12e0a18-0a26-4d55-891a-a97cc788b817-0000'
for gc 6.99999518812444days in the future
3: I0920 00:49:57.415827 15894 slave.cpp:867] Agent terminating
3: I0920 00:49:57.415949 15882 master.cpp:1321] Agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0 at slave(635)@172.17.0.3:40743
(9e396e7980ec) disconnected
3: I0920 00:49:57.415967 15882 master.cpp:3304] Disconnecting agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0 at slave(635)@172.17.0.3:40743
(9e396e7980ec)
3: I0920 00:49:57.416007 15882 master.cpp:3323] Deactivating agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0 at slave(635)@172.17.0.3:40743
(9e396e7980ec)
3: I0920 00:49:57.416101 15888 hierarchical.cpp:690] Agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0 deactivated
3: I0920 00:49:57.418517 15883 master.cpp:1163] Master terminating
3: I0920 00:49:57.419116 15876 hierarchical.cpp:626] Removed agent
c12e0a18-0a26-4d55-891a-a97cc788b817-S0
3: [ OK ] ContentType/MasterAPITest.CreateAndDestroyVolumes/1 (76 ms)
3: [ RUN ] ContentType/MasterAPITest.GetWeights/0
3: I0920 00:49:57.424803 15873 cluster.cpp:162] Creating default 'local'
authorizer
3: I0920 00:49:57.427870 15893 master.cpp:445] Master
feb8a912-52a6-4dda-a8d3-ad485325d52b (9e396e7980ec) started on 172.17.0.3:40743
3: I0920 00:49:57.427896 15893 master.cpp:447] Flags at startup: --acls=""
--agent_ping_timeout="15secs" --agent_reregister_timeout="10mins"
--allocation_interval="1secs" --allocator="HierarchicalDRF"
--authenticate_agents="true" --authenticate_frameworks="true"
--authenticate_http_frameworks="true" --authenticate_http_readonly="true"
--authenticate_http_readwrite="true" --authenticators="crammd5"
--authorizers="local" --credentials="/tmp/EFGjZu/credentials"
--filter_gpu_resources="true" --framework_sorter="drf" --help="false"
--hostname_lookup="true" --http_authenticators="basic"
--http_framework_authenticators="basic" --initialize_driver_logging="true"
--log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO"
--max_agent_ping_timeouts="5" --max_completed_frameworks="50"
--max_completed_tasks_per_framework="1000"
--max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false"
--recovery_agent_removal_limit="100%" --registry="in_memory"
--registry_fetch_timeout="1mins" --registry_gc_interval="15mins"
--registry_max_agent_age="2weeks" --registry_max_agent_count="102400"
--registry_store_timeout="100secs" --registry_strict="false"
--root_submissions="true" --user_sorter="drf" --version="false"
--webui_dir="/usr/local/share/mesos/webui" --weights="role=2.0"
--work_dir="/tmp/EFGjZu/master" --zk_session_timeout="10secs"
3: I0920 00:49:57.428140 15893 master.cpp:497] Master only allowing
authenticated frameworks to register
3: I0920 00:49:57.428146 15893 master.cpp:511] Master only allowing
authenticated agents to register
3: I0920 00:49:57.428150 15893 master.cpp:524] Master only allowing
authenticated HTTP frameworks to register
3: I0920 00:49:57.428154 15893 credentials.hpp:37] Loading credentials for
authentication from '/tmp/EFGjZu/credentials'
3: I0920 00:49:57.428393 15893 master.cpp:569] Using default 'crammd5'
authenticator
3: I0920 00:49:57.428525 15893 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
3: I0920 00:49:57.428660 15893 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
3: I0920 00:49:57.428794 15893 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
3: I0920 00:49:57.428887 15893 master.cpp:649] Authorization enabled
3: I0920 00:49:57.429018 15896 hierarchical.cpp:171] Initialized hierarchical
allocator process
3: I0920 00:49:57.429052 15878 whitelist_watcher.cpp:77] No whitelist given
3: I0920 00:49:57.431334 15884 master.cpp:2166] Elected as the leading master!
3: I0920 00:49:57.431351 15884 master.cpp:1705] Recovering from registrar
3: I0920 00:49:57.431442 15883 registrar.cpp:347] Recovering registrar
3: I0920 00:49:57.431936 15883 registrar.cpp:391] Successfully fetched the
registry (0B) in 463872ns
3: I0920 00:49:57.432049 15883 registrar.cpp:495] Applied 1 operations in
40484ns; attempting to update the registry
3: I0920 00:49:57.432550 15883 registrar.cpp:552] Successfully updated the
registry in 446976ns
3: I0920 00:49:57.432657 15883 registrar.cpp:424] Successfully recovered
registrar
3: I0920 00:49:57.432992 15890 hierarchical.cpp:209] Skipping recovery of
hierarchical allocator: nothing to recover
3: I0920 00:49:57.433046 15889 master.cpp:1804] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
3: I0920 00:49:57.433194 15890 registrar.cpp:495] Applied 1 operations in
35460ns; attempting to update the registry
3: I0920 00:49:57.433574 15890 registrar.cpp:552] Successfully updated the
registry in 0ns
3: PC: @ 0x2b1bf54be930 process::EventQueue::Consumer::empty()
3: *** SIGSEGV (@0x8) received by PID 15873 (TID 0x2b1c026f7700) from PID 8;
stack trace: ***
3: @ 0x2b1bf6129330 (unknown)
3: @ 0x2b1bf54be930 process::EventQueue::Consumer::empty()
3: @ 0x2b1bf54a0b49 process::ProcessManager::resume()
3: @ 0x2b1bf54b0b41
process::ProcessManager::init_threads()::$_8::operator()()
3: @ 0x2b1bf54b0a85
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvE3$_8vEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE
3: @ 0x2b1bf54b0a55 std::_Bind_simple<>::operator()()
3: @ 0x2b1bf54b0a2c std::thread::_Impl<>::_M_run()
3: @ 0x2b1bf953fa60 (unknown)
3: @ 0x2b1bf6121184 start_thread
3: @ 0x2b1bf9dabffd (unknown)
3/3 Test #3: MesosTests .......................***Exception: SegFault254.19 sec
67% tests passed, 1 tests failed out of 3
Total Test time (real) = 273.59 sec
The following tests FAILED:
3 - MesosTests (SEGFAULT)
Errors while running CTest
make[3]: *** [CMakeFiles/check] Error 8
make[3]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[2]: Leaving directory `/mesos/build'
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1505867038-17628
Untagged: mesos-1505867038-17628:latest
Deleted: sha256:726d706f3bfe8e6eaa8ceaa73d6932149252f244d9d1a33f76e46e14b1c00cb3
Deleted: sha256:d8d5b48dad94a6573bc9badb9d0c338e7df5d7040e322e0e227549bb2da17acb
Deleted: sha256:a0fd9fb5fcb62b1f60d66e07307f4b4d6fb28e88e94d9a324038a759e5da8382
Deleted: sha256:18186095300dcd4530c70868e9c2dda111f3adc49fd7f199c6adef8be5bcdca9
Deleted: sha256:b30eefb0891981df1d558516206e51f4981e7325bfd1474e0546143b2b746401
Deleted: sha256:b9ccaf7a6f8c6a8c49c783c33e1ec127f2055d51a5c8f621e44160bbf6dd7a33
Deleted: sha256:e673987bc0530c008f1ad9b508fe2fada2791d9fbe02d090c3f0e70f372d4493
Deleted: sha256:545698f615a2c9a0a8287626d5a4717813894e8e7d8f896f98c88831d94bcf89
Deleted: sha256:15dfeb25ddbaff79092eda435a35d3676881cbfefc62820c504be96ccd6756dd
Deleted: sha256:69b9281db924721e7083a84a2edc51acca80392dfc8b1b9209e894cc2aef7753
Deleted: sha256:6a7e51e1e5b8298795675720dfb2dcf6404715a4fb91770956a0ff86ee0aec58
Deleted: sha256:0e8c84ca0d44d1e81a473fac994856a02b064a57df3ddbcc52646a2e68fa8150
Deleted: sha256:90f243086816c75e3fbdcea6ffd13640b44a57ecc3e4319865284541f9fb2451
Deleted: sha256:6a73c45f134834e36869c9765c298af176f481457a0c3a727e62f0bd3fa16c5e
Deleted: sha256:e18b40d11d8d3234f245fc6065be41bea1f0ee3947b8a65fa68ccd65cf8a27e8
Deleted: sha256:e74e8d5d17f28c626f550e32b46f2a35e002f51e921942b4a2c153223f9c8d8a
Deleted: sha256:c4d25986b1fee676b81065d14fd9e5e22686da99f76537dd600ce6e78b029c85
Deleted: sha256:4d468d68b45d523ec8efc967ce02c09a16ba9b0d2e009d97b2e25d60d6f2cecb
Deleted: sha256:a2562538ccb23bfcbaca2ad05176204ae95d30e558ab934572e5136b89fc8e41
Deleted: sha256:ef9df50bf789493aef71957adfd32c9de2a0b8e19f401ff400e81e66b5c8f263
Deleted: sha256:554822702d85b8af8628d154980d053e044f161c7116a527594b4db20a26cdd9
Deleted: sha256:b3b77604cd6172b1354eefde47211c32de53e1c931624fd15c943eadb2527529
Deleted: sha256:9b67369309ba1ec7922e7eb83ab50ce98bfb891a90928eba934b1bb102c7ad39
Deleted: sha256:c7d5e6e7d6f74fcde3e3c463bfcdd0b42b2029898fa5bd5d6c23ec64257cb0a1
Deleted: sha256:c42a6c88568c9623861c1c6a4a2f7a5356be1e5c95261039440196523df87c6e
Deleted: sha256:0bde728244074f7189a111958dad69c52976352f7325f521a221047f8916af74
Deleted: sha256:aed3d319180901c0e94973bda1cec4cd7648b5612efda5ea7744e0bb86a059d0
Deleted: sha256:6cb630a31725ce767417cabca66706a6d0b5d2dfadba9e1d6db3969350569804
Build step 'Execute shell' marked build as failure
