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)/4090/display/redirect>
------------------------------------------
[...truncated 5.25 MB...]
I0810 20:11:46.585212 30936 slave.cpp:283] Agent using credential for:
test-principal
I0810 20:11:46.585229 30936 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/MasterMaintenanceTest_InverseOffersFilters_j8Pnjb/http_credentials'
I0810 20:11:46.585407 30936 http.cpp:1026] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I0810 20:11:46.585536 30936 http.cpp:1026] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
I0810 20:11:46.586629 30936 slave.cpp:565] Agent resources:
[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0810 20:11:46.586792 30936 slave.cpp:573] Agent attributes: [ ]
I0810 20:11:46.586803 30936 slave.cpp:582] Agent hostname: maintenance-host-2
I0810 20:11:46.586915 30927 status_update_manager.cpp:177] Pausing sending
status updates
I0810 20:11:46.588059 30931 state.cpp:64] Recovering state from
'/tmp/MasterMaintenanceTest_InverseOffersFilters_ByeX2Q/meta'
I0810 20:11:46.588285 30939 status_update_manager.cpp:203] Recovering status
update manager
I0810 20:11:46.588599 30948 slave.cpp:6210] Finished recovery
I0810 20:11:46.589032 30948 slave.cpp:6392] Querying resource estimator for
oversubscribable resources
I0810 20:11:46.589252 30947 status_update_manager.cpp:177] Pausing sending
status updates
I0810 20:11:46.589258 30940 slave.cpp:971] New master detected at
master@172.17.0.2:44613
I0810 20:11:46.589351 30940 slave.cpp:1006] Detecting new master
I0810 20:11:46.589470 30940 slave.cpp:6406] Received oversubscribable resources
{} from the resource estimator
I0810 20:11:46.595855 30933 slave.cpp:1033] Authenticating with master
master@172.17.0.2:44613
I0810 20:11:46.595908 30933 slave.cpp:1044] Using default CRAM-MD5 authenticatee
I0810 20:11:46.596081 30941 authenticatee.cpp:121] Creating new client SASL
connection
I0810 20:11:46.596295 30946 master.cpp:7837] Authenticating
slave(95)@172.17.0.2:44613
I0810 20:11:46.596415 30938 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(183)@172.17.0.2:44613
I0810 20:11:46.596611 30927 authenticator.cpp:98] Creating new server SASL
connection
I0810 20:11:46.596796 30945 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I0810 20:11:46.596819 30945 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0810 20:11:46.596894 30945 authenticator.cpp:204] Received SASL authentication
start
I0810 20:11:46.596932 30945 authenticator.cpp:326] Authentication requires more
steps
I0810 20:11:46.597018 30935 authenticatee.cpp:259] Received SASL authentication
step
I0810 20:11:46.597105 30935 authenticator.cpp:232] Received SASL authentication
step
I0810 20:11:46.597128 30935 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '91d75e25ed55' server FQDN: '91d75e25ed55'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0810 20:11:46.597139 30935 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I0810 20:11:46.597163 30935 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0810 20:11:46.597187 30935 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '91d75e25ed55' server FQDN: '91d75e25ed55'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0810 20:11:46.597196 30935 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0810 20:11:46.597203 30935 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0810 20:11:46.597218 30935 authenticator.cpp:318] Authentication success
I0810 20:11:46.597288 30944 authenticatee.cpp:299] Authentication success
I0810 20:11:46.597343 30936 master.cpp:7867] Successfully authenticated
principal 'test-principal' at slave(95)@172.17.0.2:44613
I0810 20:11:46.597405 30944 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(183)@172.17.0.2:44613
I0810 20:11:46.597492 30926 slave.cpp:1128] Successfully authenticated with
master master@172.17.0.2:44613
I0810 20:11:46.597636 30926 slave.cpp:1572] Will retry registration in
13.294776ms if necessary
I0810 20:11:46.597751 30942 master.cpp:5712] Received register agent message
from slave(95)@172.17.0.2:44613 (maintenance-host-2)
I0810 20:11:46.597781 30942 master.cpp:3803] Authorizing agent with principal
'test-principal'
I0810 20:11:46.598088 30943 master.cpp:5772] Authorized registration of agent
at slave(95)@172.17.0.2:44613 (maintenance-host-2)
I0810 20:11:46.598175 30943 master.cpp:5865] Registering agent at
slave(95)@172.17.0.2:44613 (maintenance-host-2) with id
bbade152-3c1a-424e-bd77-09d2d0ec65ae-S1
I0810 20:11:46.598467 30932 registrar.cpp:495] Applied 1 operations in 71737ns;
attempting to update the registry
I0810 20:11:46.598933 30932 registrar.cpp:552] Successfully updated the
registry in 416us
I0810 20:11:46.599102 30938 master.cpp:5912] Admitted agent
bbade152-3c1a-424e-bd77-09d2d0ec65ae-S1 at slave(95)@172.17.0.2:44613
(maintenance-host-2)
I0810 20:11:46.599509 30934 slave.cpp:4887] Received ping from
slave-observer(88)@172.17.0.2:44613
I0810 20:11:46.599545 30938 master.cpp:5943] Registered agent
bbade152-3c1a-424e-bd77-09d2d0ec65ae-S1 at slave(95)@172.17.0.2:44613
(maintenance-host-2) with
[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0810 20:11:46.599728 30934 slave.cpp:1174] Registered with master
master@172.17.0.2:44613; given agent ID bbade152-3c1a-424e-bd77-09d2d0ec65ae-S1
I0810 20:11:46.599774 30945 hierarchical.cpp:593] Added agent
bbade152-3c1a-424e-bd77-09d2d0ec65ae-S1 (maintenance-host-2) with cpus:2;
mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0810 20:11:46.599849 30928 status_update_manager.cpp:184] Resuming sending
status updates
I0810 20:11:46.600041 30945 hierarchical.cpp:1925] No allocations performed
I0810 20:11:46.600054 30934 slave.cpp:1194] Checkpointing SlaveInfo to
'/tmp/MasterMaintenanceTest_InverseOffersFilters_ByeX2Q/meta/slaves/bbade152-3c1a-424e-bd77-09d2d0ec65ae-S1/slave.info'
I0810 20:11:46.600085 30945 hierarchical.cpp:1468] Performed allocation for 1
agents in 137804ns
I0810 20:11:46.600342 30934 slave.cpp:1232] Forwarding total oversubscribed
resources {}
I0810 20:11:46.600478 30937 master.cpp:6688] Received update of agent
bbade152-3c1a-424e-bd77-09d2d0ec65ae-S1 at slave(95)@172.17.0.2:44613
(maintenance-host-2) with total oversubscribed resources {}
I0810 20:11:46.600782 30944 hierarchical.cpp:660] Agent
bbade152-3c1a-424e-bd77-09d2d0ec65ae-S1 (maintenance-host-2) updated with total
resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0810 20:11:46.602514 30941 process.cpp:3933] Handling HTTP event for process
'master' with path: '/master/maintenance/schedule'
I0810 20:11:46.603631 30928 http.cpp:1166] HTTP POST for
/master/maintenance/schedule from 172.17.0.2:54986
I0810 20:11:46.604753 30930 registrar.cpp:495] Applied 1 operations in
170936ns; attempting to update the registry
I0810 20:11:46.605255 30930 registrar.cpp:552] Successfully updated the
registry in 453888ns
I0810 20:11:46.605528 30948 master.cpp:6763] Updating unavailability of agent
bbade152-3c1a-424e-bd77-09d2d0ec65ae-S0 at slave(94)@172.17.0.2:44613
(maintenance-host), starting at 2489.12074669317weeks
I0810 20:11:46.605661 30948 master.cpp:6763] Updating unavailability of agent
bbade152-3c1a-424e-bd77-09d2d0ec65ae-S1 at slave(95)@172.17.0.2:44613
(maintenance-host-2), starting at 2489.12074669317weeks
I0810 20:11:46.605859 30943 hierarchical.cpp:1925] No allocations performed
I0810 20:11:46.605904 30943 hierarchical.cpp:1468] Performed allocation for 1
agents in 135220ns
I0810 20:11:46.606134 30943 hierarchical.cpp:1925] No allocations performed
I0810 20:11:46.606176 30943 hierarchical.cpp:1468] Performed allocation for 1
agents in 123161ns
I0810 20:11:46.607302 30924 scheduler.cpp:184] Version: 1.4.0
I0810 20:11:46.607758 30926 scheduler.cpp:470] New master detected at
master@172.17.0.2:44613
I0810 20:11:46.607781 30926 scheduler.cpp:479] Waiting for 0ns before
initiating a re-(connection) attempt with the master
I0810 20:11:46.610532 30929 scheduler.cpp:361] Connected with the master at
http://172.17.0.2:44613/master/api/v1/scheduler
I0810 20:11:46.611585 30944 scheduler.cpp:243] Sending SUBSCRIBE call to
http://172.17.0.2:44613/master/api/v1/scheduler
I0810 20:11:46.612581 30940 process.cpp:3933] Handling HTTP event for process
'master' with path: '/master/api/v1/scheduler'
I0810 20:11:46.613721 30946 http.cpp:1166] HTTP POST for
/master/api/v1/scheduler from 172.17.0.2:54988
I0810 20:11:46.613934 30946 master.cpp:2545] Received subscription request for
HTTP framework 'default'
I0810 20:11:46.613970 30946 master.cpp:2228] Authorizing framework principal
'test-principal' to receive offers for roles '{ * }'
I0810 20:11:46.614362 30936 master.cpp:2681] Subscribing framework 'default'
with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
I0810 20:11:46.614979 30934 master.hpp:2217] Sending heartbeat to
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.615085 30928 hierarchical.cpp:303] Added framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.615545 30943 scheduler.cpp:676] Enqueuing event SUBSCRIBED
received from http://172.17.0.2:44613/master/api/v1/scheduler
I0810 20:11:46.616021 30943 scheduler.cpp:676] Enqueuing event HEARTBEAT
received from http://172.17.0.2:44613/master/api/v1/scheduler
I0810 20:11:46.617043 30928 hierarchical.cpp:1468] Performed allocation for 2
agents in 1.842124ms
I0810 20:11:46.617599 30939 master.cpp:7667] Sending 2 offers to framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 (default)
I0810 20:11:46.618413 30939 master.cpp:7756] Sending 2 inverse offers to
framework bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 (default)
I0810 20:11:46.619710 30947 scheduler.cpp:676] Enqueuing event OFFERS received
from http://172.17.0.2:44613/master/api/v1/scheduler
I0810 20:11:46.620327 30932 scheduler.cpp:676] Enqueuing event INVERSE_OFFERS
received from http://172.17.0.2:44613/master/api/v1/scheduler
I0810 20:11:46.623620 30945 hierarchical.cpp:1925] No allocations performed
I0810 20:11:46.623800 30945 hierarchical.cpp:1468] Performed allocation for 2
agents in 273117ns
I0810 20:11:46.624935 30936 scheduler.cpp:243] Sending ACCEPT call to
http://172.17.0.2:44613/master/api/v1/scheduler
I0810 20:11:46.625396 30936 scheduler.cpp:243] Sending ACCEPT call to
http://172.17.0.2:44613/master/api/v1/scheduler
I0810 20:11:46.626019 30934 process.cpp:3933] Handling HTTP event for process
'master' with path: '/master/api/v1/scheduler'
I0810 20:11:46.627271 30935 http.cpp:1166] HTTP POST for
/master/api/v1/scheduler from 172.17.0.2:54990
I0810 20:11:46.628111 30935 master.cpp:9164] Removing offer
bbade152-3c1a-424e-bd77-09d2d0ec65ae-O0
I0810 20:11:46.628273 30935 master.cpp:4153] Processing ACCEPT call for offers:
[ bbade152-3c1a-424e-bd77-09d2d0ec65ae-O0 ] on agent
bbade152-3c1a-424e-bd77-09d2d0ec65ae-S0 at slave(94)@172.17.0.2:44613
(maintenance-host) for framework bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
(default)
I0810 20:11:46.628358 30935 master.cpp:3530] Authorizing framework principal
'test-principal' to launch task f15356ba-a60d-467b-a626-ab40d6a8e93a
I0810 20:11:46.629215 30935 process.cpp:3933] Handling HTTP event for process
'master' with path: '/master/api/v1/scheduler'
W0810 20:11:46.630560 30935 validation.cpp:1294] Executor 'executor-1' for task
'f15356ba-a60d-467b-a626-ab40d6a8e93a' uses less CPUs (None) than the minimum
required (0.01). Please update your executor, as this will be mandatory in
future releases.
W0810 20:11:46.630589 30935 validation.cpp:1306] Executor 'executor-1' for task
'f15356ba-a60d-467b-a626-ab40d6a8e93a' uses less memory (None) than the minimum
required (32MB). Please update your executor, as this will be mandatory in
future releases.
I0810 20:11:46.631094 30935 master.cpp:9724] Adding task
f15356ba-a60d-467b-a626-ab40d6a8e93a with resources
[{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
on agent bbade152-3c1a-424e-bd77-09d2d0ec65ae-S0 at slave(94)@172.17.0.2:44613
(maintenance-host)
I0810 20:11:46.631633 30935 master.cpp:4816] Launching task
f15356ba-a60d-467b-a626-ab40d6a8e93a of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 (default) with resources
[{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
on agent bbade152-3c1a-424e-bd77-09d2d0ec65ae-S0 at slave(94)@172.17.0.2:44613
(maintenance-host)
I0810 20:11:46.632164 30928 slave.cpp:1701] Got assigned task
'f15356ba-a60d-467b-a626-ab40d6a8e93a' for framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.632786 30944 http.cpp:1166] HTTP POST for
/master/api/v1/scheduler from 172.17.0.2:54990
I0810 20:11:46.632977 30928 slave.cpp:1982] Authorizing task
'f15356ba-a60d-467b-a626-ab40d6a8e93a' for framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.633045 30928 slave.cpp:6709] Authorizing framework principal
'test-principal' to launch task f15356ba-a60d-467b-a626-ab40d6a8e93a
I0810 20:11:46.633492 30930 hierarchical.cpp:887] Updated allocation of
framework bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 on agent
bbade152-3c1a-424e-bd77-09d2d0ec65ae-S0 from cpus(allocated: *):2;
mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated:
*):[31000-32000] to cpus(allocated: *):2; mem(allocated: *):1024;
disk(allocated: *):1024; ports(allocated: *):[31000-32000]
I0810 20:11:46.633826 30928 slave.cpp:2169] Launching task
'f15356ba-a60d-467b-a626-ab40d6a8e93a' for framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.633839 30944 master.cpp:9164] Removing offer
bbade152-3c1a-424e-bd77-09d2d0ec65ae-O1
I0810 20:11:46.633999 30944 master.cpp:4153] Processing ACCEPT call for offers:
[ bbade152-3c1a-424e-bd77-09d2d0ec65ae-O1 ] on agent
bbade152-3c1a-424e-bd77-09d2d0ec65ae-S1 at slave(95)@172.17.0.2:44613
(maintenance-host-2) for framework bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
(default)
I0810 20:11:46.634104 30944 master.cpp:3530] Authorizing framework principal
'test-principal' to launch task aacaff4e-27d0-4092-9e10-7cf65d8738cb
I0810 20:11:46.634670 30928 paths.cpp:578] Trying to chown
'/tmp/MasterMaintenanceTest_InverseOffersFilters_D01h4x/slaves/bbade152-3c1a-424e-bd77-09d2d0ec65ae-S0/frameworks/bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000/executors/executor-1/runs/261928d1-be0b-4acc-8e54-e9ee1e6d5cb1'
to user 'mesos'
I0810 20:11:46.634943 30928 slave.cpp:7165] Launching executor 'executor-1' of
framework bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 with resources [] in work
directory
'/tmp/MasterMaintenanceTest_InverseOffersFilters_D01h4x/slaves/bbade152-3c1a-424e-bd77-09d2d0ec65ae-S0/frameworks/bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000/executors/executor-1/runs/261928d1-be0b-4acc-8e54-e9ee1e6d5cb1'
I0810 20:11:46.635509 30928 slave.cpp:2856] Launching container
261928d1-be0b-4acc-8e54-e9ee1e6d5cb1 for executor 'executor-1' of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.635886 30928 slave.cpp:2398] Queued task
'f15356ba-a60d-467b-a626-ab40d6a8e93a' for executor 'executor-1' of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.635995 30928 slave.cpp:924] Successfully attached file
'/tmp/MasterMaintenanceTest_InverseOffersFilters_D01h4x/slaves/bbade152-3c1a-424e-bd77-09d2d0ec65ae-S0/frameworks/bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000/executors/executor-1/runs/261928d1-be0b-4acc-8e54-e9ee1e6d5cb1'
I0810 20:11:46.636044 30928 slave.cpp:924] Successfully attached file
'/tmp/MasterMaintenanceTest_InverseOffersFilters_D01h4x/slaves/bbade152-3c1a-424e-bd77-09d2d0ec65ae-S0/frameworks/bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000/executors/executor-1/runs/261928d1-be0b-4acc-8e54-e9ee1e6d5cb1'
W0810 20:11:46.636479 30944 validation.cpp:1294] Executor 'executor-2' for task
'aacaff4e-27d0-4092-9e10-7cf65d8738cb' uses less CPUs (None) than the minimum
required (0.01). Please update your executor, as this will be mandatory in
future releases.
W0810 20:11:46.636505 30944 validation.cpp:1306] Executor 'executor-2' for task
'aacaff4e-27d0-4092-9e10-7cf65d8738cb' uses less memory (None) than the minimum
required (32MB). Please update your executor, as this will be mandatory in
future releases.
I0810 20:11:46.636971 30944 master.cpp:9724] Adding task
aacaff4e-27d0-4092-9e10-7cf65d8738cb with resources
[{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
on agent bbade152-3c1a-424e-bd77-09d2d0ec65ae-S1 at slave(95)@172.17.0.2:44613
(maintenance-host-2)
I0810 20:11:46.637460 30944 master.cpp:4816] Launching task
aacaff4e-27d0-4092-9e10-7cf65d8738cb of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 (default) with resources
[{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
on agent bbade152-3c1a-424e-bd77-09d2d0ec65ae-S1 at slave(95)@172.17.0.2:44613
(maintenance-host-2)
I0810 20:11:46.637845 30926 process.cpp:3228] Attempting to spawn already
spawned process version@172.17.0.2:44613
I0810 20:11:46.637970 30939 slave.cpp:1701] Got assigned task
'aacaff4e-27d0-4092-9e10-7cf65d8738cb' for framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.637997 30926 exec.cpp:162] Version: 1.4.0
I0810 20:11:46.638236 30934 exec.cpp:212] Executor started at:
executor(28)@172.17.0.2:44613 with pid 30924
I0810 20:11:46.638396 30927 slave.cpp:3881] Got registration for executor
'executor-1' of framework bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 from
executor(28)@172.17.0.2:44613
I0810 20:11:46.638541 30939 slave.cpp:1982] Authorizing task
'aacaff4e-27d0-4092-9e10-7cf65d8738cb' for framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.638597 30939 slave.cpp:6709] Authorizing framework principal
'test-principal' to launch task aacaff4e-27d0-4092-9e10-7cf65d8738cb
I0810 20:11:46.638685 30947 exec.cpp:237] Executor registered on agent
bbade152-3c1a-424e-bd77-09d2d0ec65ae-S0
I0810 20:11:46.638736 30947 exec.cpp:249] Executor::registered took 36278ns
I0810 20:11:46.639225 30927 slave.cpp:2603] Sending queued task
'f15356ba-a60d-467b-a626-ab40d6a8e93a' to executor 'executor-1' of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 at executor(28)@172.17.0.2:44613
I0810 20:11:46.639272 30943 slave.cpp:2169] Launching task
'aacaff4e-27d0-4092-9e10-7cf65d8738cb' for framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.639291 30936 hierarchical.cpp:887] Updated allocation of
framework bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 on agent
bbade152-3c1a-424e-bd77-09d2d0ec65ae-S1 from cpus(allocated: *):2;
mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated:
*):[31000-32000] to cpus(allocated: *):2; mem(allocated: *):1024;
disk(allocated: *):1024; ports(allocated: *):[31000-32000]
I0810 20:11:46.639505 30937 exec.cpp:331] Executor asked to run task
'f15356ba-a60d-467b-a626-ab40d6a8e93a'
I0810 20:11:46.639585 30937 exec.cpp:340] Executor::launchTask took 67677ns
I0810 20:11:46.639684 30937 exec.cpp:571] Executor sending status update
TASK_RUNNING (UUID: 180d590b-200a-4b70-b12a-376fff766853) for task
f15356ba-a60d-467b-a626-ab40d6a8e93a of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.639946 30943 paths.cpp:578] Trying to chown
'/tmp/MasterMaintenanceTest_InverseOffersFilters_ByeX2Q/slaves/bbade152-3c1a-424e-bd77-09d2d0ec65ae-S1/frameworks/bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000/executors/executor-2/runs/11638a4e-a776-4075-8ea8-80f349a606d5'
to user 'mesos'
I0810 20:11:46.639931 30929 slave.cpp:4345] Handling status update TASK_RUNNING
(UUID: 180d590b-200a-4b70-b12a-376fff766853) for task
f15356ba-a60d-467b-a626-ab40d6a8e93a of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 from executor(28)@172.17.0.2:44613
I0810 20:11:46.640137 30943 slave.cpp:7165] Launching executor 'executor-2' of
framework bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 with resources [] in work
directory
'/tmp/MasterMaintenanceTest_InverseOffersFilters_ByeX2Q/slaves/bbade152-3c1a-424e-bd77-09d2d0ec65ae-S1/frameworks/bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000/executors/executor-2/runs/11638a4e-a776-4075-8ea8-80f349a606d5'
I0810 20:11:46.640480 30928 status_update_manager.cpp:323] Received status
update TASK_RUNNING (UUID: 180d590b-200a-4b70-b12a-376fff766853) for task
f15356ba-a60d-467b-a626-ab40d6a8e93a of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.640519 30928 status_update_manager.cpp:500] Creating
StatusUpdate stream for task f15356ba-a60d-467b-a626-ab40d6a8e93a of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.640537 30943 slave.cpp:2856] Launching container
11638a4e-a776-4075-8ea8-80f349a606d5 for executor 'executor-2' of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.640830 30943 slave.cpp:2398] Queued task
'aacaff4e-27d0-4092-9e10-7cf65d8738cb' for executor 'executor-2' of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.640910 30943 slave.cpp:924] Successfully attached file
'/tmp/MasterMaintenanceTest_InverseOffersFilters_ByeX2Q/slaves/bbade152-3c1a-424e-bd77-09d2d0ec65ae-S1/frameworks/bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000/executors/executor-2/runs/11638a4e-a776-4075-8ea8-80f349a606d5'
I0810 20:11:46.640960 30943 slave.cpp:924] Successfully attached file
'/tmp/MasterMaintenanceTest_InverseOffersFilters_ByeX2Q/slaves/bbade152-3c1a-424e-bd77-09d2d0ec65ae-S1/frameworks/bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000/executors/executor-2/runs/11638a4e-a776-4075-8ea8-80f349a606d5'
I0810 20:11:46.640971 30928 status_update_manager.cpp:377] Forwarding update
TASK_RUNNING (UUID: 180d590b-200a-4b70-b12a-376fff766853) for task
f15356ba-a60d-467b-a626-ab40d6a8e93a of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 to the agent
I0810 20:11:46.641196 30944 slave.cpp:4797] Forwarding the update TASK_RUNNING
(UUID: 180d590b-200a-4b70-b12a-376fff766853) for task
f15356ba-a60d-467b-a626-ab40d6a8e93a of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 to master@172.17.0.2:44613
I0810 20:11:46.641394 30944 slave.cpp:4691] Status update manager successfully
handled status update TASK_RUNNING (UUID: 180d590b-200a-4b70-b12a-376fff766853)
for task f15356ba-a60d-467b-a626-ab40d6a8e93a of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.641434 30944 slave.cpp:4707] Sending acknowledgement for status
update TASK_RUNNING (UUID: 180d590b-200a-4b70-b12a-376fff766853) for task
f15356ba-a60d-467b-a626-ab40d6a8e93a of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 to executor(28)@172.17.0.2:44613
I0810 20:11:46.641530 30942 master.cpp:6846] Status update TASK_RUNNING (UUID:
180d590b-200a-4b70-b12a-376fff766853) for task
f15356ba-a60d-467b-a626-ab40d6a8e93a of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 from agent
bbade152-3c1a-424e-bd77-09d2d0ec65ae-S0 at slave(94)@172.17.0.2:44613
(maintenance-host)
I0810 20:11:46.641561 30934 exec.cpp:388] Executor received status update
acknowledgement 180d590b-200a-4b70-b12a-376fff766853 for task
f15356ba-a60d-467b-a626-ab40d6a8e93a of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.641577 30942 master.cpp:6908] Forwarding status update
TASK_RUNNING (UUID: 180d590b-200a-4b70-b12a-376fff766853) for task
f15356ba-a60d-467b-a626-ab40d6a8e93a of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.641944 30942 master.cpp:8933] Updating the state of task
f15356ba-a60d-467b-a626-ab40d6a8e93a of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 (latest state: TASK_RUNNING, status
update state: TASK_RUNNING)
*** Aborted at 1502395906 (unix time) try "date -d @1502395906" if you are
using GNU date ***
IPC: @ 0x2ad5ecca882d (unknown)
0810 20:11:46.642756 30948 process.cpp:3228] Attempting to spawn already
spawned process version@172.17.0.2:44613
I0810 20:11:46.642766 30933 scheduler.cpp:676] Enqueuing event UPDATE received
from http://172.17.0.2:44613/master/api/v1/scheduler
I0810 20:11:46.642904 30948 exec.cpp:162] Version: 1.4.0
*** SIGSEGV (@0xb0) received by PID 30924 (TID 0x2ad5f6aa7700) from PID 176;
stack trace: ***
I0810 20:11:46.643076 30938 exec.cpp:212] Executor started at:
executor(29)@172.17.0.2:44613 with pid 30924
I0810 20:11:46.643234 30931 slave.cpp:3881] Got registration for executor
'executor-2' of framework bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 from
executor(29)@172.17.0.2:44613
@ 0x2ad5ec23f330 (unknown)
@ 0x2ad5ecca882d (unknown)
I0810 20:11:46.643530 30936 exec.cpp:237] Executor registered on agent
bbade152-3c1a-424e-bd77-09d2d0ec65ae-S1
I0810 20:11:46.643579 30936 exec.cpp:249] Executor::registered took 39293ns
@ 0x2ad5ecc9e6f1 (unknown)
@ 0x2ad5eccf5c9e (unknown)
I0810 20:11:46.644045 30931 slave.cpp:2603] Sending queued task
'aacaff4e-27d0-4092-9e10-7cf65d8738cb' to executor 'executor-2' of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 at executor(29)@172.17.0.2:44613
I0810 20:11:46.644315 30945 exec.cpp:331] Executor asked to run task
'aacaff4e-27d0-4092-9e10-7cf65d8738cb'
I0810 20:11:46.644390 30945 exec.cpp:340] Executor::launchTask took 64178ns
I0810 20:11:46.644484 30945 exec.cpp:571] Executor sending status update
TASK_RUNNING (UUID: f10bda53-afd5-406d-9ce2-95f0ea25e316) for task
aacaff4e-27d0-4092-9e10-7cf65d8738cb of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.644687 30943 slave.cpp:4345] Handling status update TASK_RUNNING
(UUID: f10bda53-afd5-406d-9ce2-95f0ea25e316) for task
aacaff4e-27d0-4092-9e10-7cf65d8738cb of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 from executor(29)@172.17.0.2:44613
I0810 20:11:46.645148 30934 status_update_manager.cpp:323] Received status
update TASK_RUNNING (UUID: f10bda53-afd5-406d-9ce2-95f0ea25e316) for task
aacaff4e-27d0-4092-9e10-7cf65d8738cb of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.645192 30934 status_update_manager.cpp:500] Creating
StatusUpdate stream for task aacaff4e-27d0-4092-9e10-7cf65d8738cb of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.645562 30934 status_update_manager.cpp:377] Forwarding update
TASK_RUNNING (UUID: f10bda53-afd5-406d-9ce2-95f0ea25e316) for task
aacaff4e-27d0-4092-9e10-7cf65d8738cb of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 to the agent
I0810 20:11:46.645752 30942 slave.cpp:4797] Forwarding the update TASK_RUNNING
(UUID: f10bda53-afd5-406d-9ce2-95f0ea25e316) for task
aacaff4e-27d0-4092-9e10-7cf65d8738cb of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 to master@172.17.0.2:44613
I0810 20:11:46.645923 30942 slave.cpp:4691] Status update manager successfully
handled status update TASK_RUNNING (UUID: f10bda53-afd5-406d-9ce2-95f0ea25e316)
for task aacaff4e-27d0-4092-9e10-7cf65d8738cb of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.645968 30942 slave.cpp:4707] Sending acknowledgement for status
update TASK_RUNNING (UUID: f10bda53-afd5-406d-9ce2-95f0ea25e316) for task
aacaff4e-27d0-4092-9e10-7cf65d8738cb of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 to executor(29)@172.17.0.2:44613
I0810 20:11:46.646031 30926 master.cpp:6846] Status update TASK_RUNNING (UUID:
f10bda53-afd5-406d-9ce2-95f0ea25e316) for task
aacaff4e-27d0-4092-9e10-7cf65d8738cb of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 from agent
bbade152-3c1a-424e-bd77-09d2d0ec65ae-S1 at slave(95)@172.17.0.2:44613
(maintenance-host-2)
I0810 20:11:46.646050 30932 scheduler.cpp:243] Sending ACKNOWLEDGE call to
http://172.17.0.2:44613/master/api/v1/scheduler
I0810 20:11:46.646076 30926 master.cpp:6908] Forwarding status update
TASK_RUNNING (UUID: f10bda53-afd5-406d-9ce2-95f0ea25e316) for task
aacaff4e-27d0-4092-9e10-7cf65d8738cb of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.646111 30930 exec.cpp:388] Executor received status update
acknowledgement f10bda53-afd5-406d-9ce2-95f0ea25e316 for task
aacaff4e-27d0-4092-9e10-7cf65d8738cb of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.646366 30926 master.cpp:8933] Updating the state of task
aacaff4e-27d0-4092-9e10-7cf65d8738cb of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 (latest state: TASK_RUNNING, status
update state: TASK_RUNNING)
I0810 20:11:46.647047 30946 process.cpp:3933] Handling HTTP event for process
'master' with path: '/master/api/v1/scheduler'
@ 0x1b63847 os::strerror()
@ 0x1b637c7 ErrnoError::ErrnoError()
@ 0x1b63794 ErrnoError::ErrnoError()
@ 0x2ad5e837ece2 process::io::internal::read()::$_3::operator()()
@ 0x2ad5e837dfc9 process::internal::Loop<>::start()
@ 0x2ad5e835d5a0 process::loop<>()
@ 0x2ad5e835d4d7 process::io::internal::read()
@ 0x2ad5e835dab1 process::io::read()
@ 0x2ad5e83f4ece process::network::internal::PollSocketImpl::recv()
@ 0x2ad5e852be1a process::network::internal::SocketImpl::recv()
I0810 20:11:46.686005 30936 http.cpp:1166] HTTP POST for
/master/api/v1/scheduler from 172.17.0.2:54990
I0810 20:11:46.686241 30936 master.cpp:5477] Processing ACKNOWLEDGE call
180d590b-200a-4b70-b12a-376fff766853 for task
f15356ba-a60d-467b-a626-ab40d6a8e93a of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000 (default) on agent
bbade152-3c1a-424e-bd77-09d2d0ec65ae-S0
@ 0x2ad5e832170e process::network::internal::Socket<>::recv()
I0810 20:11:46.686591 30931 status_update_manager.cpp:395] Received status
update acknowledgement (UUID: 180d590b-200a-4b70-b12a-376fff766853) for task
f15356ba-a60d-467b-a626-ab40d6a8e93a of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
I0810 20:11:46.686897 30931 slave.cpp:3609] Status update manager successfully
handled status update acknowledgement (UUID:
180d590b-200a-4b70-b12a-376fff766853) for task
f15356ba-a60d-467b-a626-ab40d6a8e93a of framework
bbade152-3c1a-424e-bd77-09d2d0ec65ae-0000
@ 0x2ad5e83215a2 process::http::internal::ConnectionProcess::read()
@ 0x2ad5e83226c2 process::http::internal::ConnectionProcess::_read()
@ 0x2ad5e8326440
_ZZN7process8dispatchINS_4http8internal17ConnectionProcessERKNS_6FutureISsEES7_EEvRKNS_3PIDIT_EEMS9_FvT0_EOT1_ENKUlRS5_PNS_11ProcessBaseEE_clESI_SK_
@ 0x2ad5e83262de
_ZNSt5_BindIFZN7process8dispatchINS0_4http8internal17ConnectionProcessERKNS0_6FutureISsEES8_EEvRKNS0_3PIDIT_EEMSA_FvT0_EOT1_EUlRS6_PNS0_11ProcessBaseEE_S6_St12_PlaceholderILi1EEEE6__callIvJOSL_EJLm0ELm1EEEESA_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
@ 0x2ad5e832625d
_ZNSt5_BindIFZN7process8dispatchINS0_4http8internal17ConnectionProcessERKNS0_6FutureISsEES8_EEvRKNS0_3PIDIT_EEMSA_FvT0_EOT1_EUlRS6_PNS0_11ProcessBaseEE_S6_St12_PlaceholderILi1EEEEclIJSL_EvEESE_DpOT_
@ 0x2ad5e8325f22
_ZNSt17_Function_handlerIFvPN7process11ProcessBaseEESt5_BindIFZNS0_8dispatchINS0_4http8internal17ConnectionProcessERKNS0_6FutureISsEESC_EEvRKNS0_3PIDIT_EEMSE_FvT0_EOT1_EUlRSA_S2_E_SA_St12_PlaceholderILi1EEEEE9_M_invokeERKSt9_Any_dataS2_
@ 0x2ad5e844f3b8 std::function<>::operator()()
@ 0x2ad5e8433ed4 process::ProcessBase::visit()
@ 0x2ad5e84d859e process::DispatchEvent::visit()
@ 0x1ba9631 process::ProcessBase::serve()
@ 0x2ad5e8431c7f process::ProcessManager::resume()
@ 0x2ad5e84407f1
process::ProcessManager::init_threads()::$_8::operator()()
@ 0x2ad5e8440735
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvE3$_8vEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE
@ 0x2ad5e8440705 std::_Bind_simple<>::operator()()
@ 0x2ad5e84406dc std::thread::_Impl<>::_M_run()
@ 0x2ad5ec4fea60 (unknown)
@ 0x2ad5ec237184 start_thread
make[3]: Leaving directory `/mesos/build'
make[3]: *** [CMakeFiles/check] Segmentation fault (core dumped)
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-1502394154-19543
Untagged: mesos-1502394154-19543:latest
Deleted: sha256:bc5e05eeab8eb83e0af857c0ff783ba721aa528ee43fdb03a999f363c03f4c19
Deleted: sha256:ac062095f80e82744f294cbf11d57af33280d85bc4d8f5fe786138a6975d3dc0
Deleted: sha256:15ad8a132a43b1af5e051a5bebabeb7ddfee118fc929b2a3652030af0abc865c
Deleted: sha256:33aa26b395ac44746db2c63e10009b8f04f94d05c2c7ec09f47295cb252d0da5
Deleted: sha256:4a9c87385171fd6167ebcbd4206fd85875cd1ac8b42dd756bf6e94bb93a17390
Deleted: sha256:eea2b9688a252a240f0dc6b67895d5461084fa74715de23a2094979a9acb16b4
Deleted: sha256:5603d579e04d8653f8e6c302e144e47a4406be40c05bf62f7bc62a8d9c3ce0ec
Deleted: sha256:2adbb87594adb090cc7e5f1ef0e3fee3652969c38108ec7b5d35d59ef96b1f41
Deleted: sha256:f4610f3b735fde92964b6b5702b0098baf664df74afb92d9350f33741330ebd6
Deleted: sha256:d9d54e719cc95403aef6320b4062221dfec7585562c6c85a467b6d652a460942
Deleted: sha256:95f5d527daf761f3a9f3b1781714c6c8b15aaa6a183f3f4fbfd14bf6aee5bec0
Deleted: sha256:7f94375fa0a80a7301dd8133e5cac740bba351546c351d2d9c51d61ee6361c26
Deleted: sha256:51ee81e23b223acc92615ecb0230b3a38342d87613888e364b05dc4396032780
Deleted: sha256:f444c1a7f40f3b5d0fa7df91388e4903c8407bc479131766d12af88b479ff803
Deleted: sha256:21a5b47c05f78df9b8f2f5de8cc69442f7f9481ec60659ddd4cdcce2d3f3dea0
Deleted: sha256:a2c7f8243c7e6d21e6ba3f5f74772625fa4d25b3566c1f0259fbe7826e247e6a
Deleted: sha256:3cc211df380ac68d1c6b51de01d84873be5fe59da51cc7d923d099073267d4c4
Deleted: sha256:18cbb5f3ab6d9b1dbdd3928cf7b6c0c7455c4dc90d72360b9f925967bef559d9
Deleted: sha256:04b7feb29a0b9507a1e730b6b1d3e9249f50d0404dde72616c7456ada221245d
Deleted: sha256:cf5347057f8f51899fd73ef742e4e77ae96356a6767144b909294ae5ed804bfc
Deleted: sha256:b44971ea525bc8a59d40ebb67a552be6cfeb33642510d2003e1083ea6065cd4c
Deleted: sha256:fdec67c2cda49ea4a3de568188691354d7d1c8da76e4ebc2a195304d1f8f0d1e
Deleted: sha256:ba6ce4d437e488f60c55d66fae49b6d5d83e3023e03d59139ccf121c4a2a588c
Deleted: sha256:23f604ec6f8df41a822086722d887ec6fc48cd0466e84b9232e7f71ee0f9b7c6
Build step 'Execute shell' marked build as failure
