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