You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/09/01 04:51:02 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #4179
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/4179/display/redirect?page=changes>
Changes:
[yujie.jay] Used 'undiscardable' to protect a future in MesosContainerizer.
[yujie.jay] Added MESOS-7926 to 1.3.2 CHANGELOG.
[yujie.jay] Added MESOS-7926 to 1.2.3 CHANGELOG.
------------------------------------------
[...truncated 27.90 MB...]
I0901 04:50:55.398054 785 process.cpp:3956] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0901 04:50:55.399693 790 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:38628
I0901 04:50:55.399786 789 state.cpp:64] Recovering state from '/tmp/ContentType_SchedulerTest_KillTask_0_TmVaxn/meta'
I0901 04:50:55.400028 790 master.cpp:2548] Received subscription request for HTTP framework 'default'
I0901 04:50:55.400079 792 status_update_manager.cpp:203] Recovering status update manager
I0901 04:50:55.400080 790 master.cpp:2231] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0901 04:50:55.400668 782 slave.cpp:6296] Finished recovery
I0901 04:50:55.400671 784 master.cpp:2684] Subscribing framework 'default' with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
I0901 04:50:55.401180 782 slave.cpp:6478] Querying resource estimator for oversubscribable resources
I0901 04:50:55.401391 778 master.hpp:349] Sending heartbeat to framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.401417 781 slave.cpp:6492] Received oversubscribable resources {} from the resource estimator
I0901 04:50:55.401516 797 hierarchical.cpp:303] Added framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.401605 781 slave.cpp:987] New master detected at master@172.17.0.4:33678
I0901 04:50:55.401619 786 status_update_manager.cpp:177] Pausing sending status updates
I0901 04:50:55.401684 781 slave.cpp:1022] Detecting new master
I0901 04:50:55.401861 797 hierarchical.cpp:1943] No allocations performed
I0901 04:50:55.401906 797 hierarchical.cpp:2033] No inverse offers to send out!
I0901 04:50:55.401937 797 hierarchical.cpp:1486] Performed allocation for 0 agents in 141036ns
I0901 04:50:55.402361 790 scheduler.cpp:676] Enqueuing event SUBSCRIBED received from http://172.17.0.4:33678/master/api/v1/scheduler
I0901 04:50:55.402809 790 scheduler.cpp:676] Enqueuing event HEARTBEAT received from http://172.17.0.4:33678/master/api/v1/scheduler
I0901 04:50:55.403458 778 slave.cpp:1049] Authenticating with master master@172.17.0.4:33678
I0901 04:50:55.403527 778 slave.cpp:1060] Using default CRAM-MD5 authenticatee
I0901 04:50:55.403741 791 authenticatee.cpp:121] Creating new client SASL connection
I0901 04:50:55.404021 792 master.cpp:7834] Authenticating slave(740)@172.17.0.4:33678
I0901 04:50:55.404129 797 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1368)@172.17.0.4:33678
I0901 04:50:55.404357 788 authenticator.cpp:98] Creating new server SASL connection
I0901 04:50:55.404546 796 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0901 04:50:55.404577 796 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0901 04:50:55.404716 793 authenticator.cpp:204] Received SASL authentication start
I0901 04:50:55.404804 793 authenticator.cpp:326] Authentication requires more steps
I0901 04:50:55.404901 793 authenticatee.cpp:259] Received SASL authentication step
I0901 04:50:55.405020 776 authenticator.cpp:232] Received SASL authentication step
I0901 04:50:55.405061 776 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '92967e954293' server FQDN: '92967e954293' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0901 04:50:55.405072 776 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0901 04:50:55.405117 776 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0901 04:50:55.405138 776 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '92967e954293' server FQDN: '92967e954293' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0901 04:50:55.405145 776 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0901 04:50:55.405153 776 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0901 04:50:55.405169 776 authenticator.cpp:318] Authentication success
I0901 04:50:55.405242 795 authenticatee.cpp:299] Authentication success
I0901 04:50:55.405328 784 master.cpp:7864] Successfully authenticated principal 'test-principal' at slave(740)@172.17.0.4:33678
I0901 04:50:55.405370 776 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1368)@172.17.0.4:33678
I0901 04:50:55.405493 775 slave.cpp:1144] Successfully authenticated with master master@172.17.0.4:33678
I0901 04:50:55.405738 775 slave.cpp:1623] Will retry registration in 1.373604ms if necessary
I0901 04:50:55.406013 775 master.cpp:5717] Received register agent message from slave(740)@172.17.0.4:33678 (92967e954293)
I0901 04:50:55.406047 775 master.cpp:3806] Authorizing agent with principal 'test-principal'
I0901 04:50:55.406615 782 master.cpp:5777] Authorized registration of agent at slave(740)@172.17.0.4:33678 (92967e954293)
I0901 04:50:55.406780 782 master.cpp:5870] Registering agent at slave(740)@172.17.0.4:33678 (92967e954293) with id 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0
I0901 04:50:55.407171 787 registrar.cpp:495] Applied 1 operations in 90687ns; attempting to update the registry
I0901 04:50:55.408010 787 registrar.cpp:552] Successfully updated the registry in 761088ns
I0901 04:50:55.408185 792 slave.cpp:1623] Will retry registration in 13.64861ms if necessary
I0901 04:50:55.408246 787 master.cpp:5917] Admitted agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 at slave(740)@172.17.0.4:33678 (92967e954293)
I0901 04:50:55.408886 787 master.cpp:5948] Registered agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 at slave(740)@172.17.0.4:33678 (92967e954293) 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"}]
I0901 04:50:55.409317 780 hierarchical.cpp:593] Added agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 (92967e954293) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0901 04:50:55.409344 787 master.cpp:5717] Received register agent message from slave(740)@172.17.0.4:33678 (92967e954293)
I0901 04:50:55.409374 787 master.cpp:3806] Authorizing agent with principal 'test-principal'
I0901 04:50:55.409790 793 master.cpp:5777] Authorized registration of agent at slave(740)@172.17.0.4:33678 (92967e954293)
I0901 04:50:55.409865 793 master.cpp:5848] Agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 at slave(740)@172.17.0.4:33678 (92967e954293) already registered, resending acknowledgement
I0901 04:50:55.409921 796 slave.cpp:4963] Received ping from slave-observer(669)@172.17.0.4:33678
I0901 04:50:55.410086 796 slave.cpp:1190] Registered with master master@172.17.0.4:33678; given agent ID 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0
I0901 04:50:55.410228 786 status_update_manager.cpp:184] Resuming sending status updates
I0901 04:50:55.410497 796 slave.cpp:1210] Checkpointing SlaveInfo to '/tmp/ContentType_SchedulerTest_KillTask_0_TmVaxn/meta/slaves/4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0/slave.info'
I0901 04:50:55.410897 796 slave.cpp:1259] Forwarding total oversubscribed resources {}
W0901 04:50:55.410993 796 slave.cpp:1245] Already registered with master master@172.17.0.4:33678
I0901 04:50:55.411012 796 slave.cpp:1259] Forwarding total oversubscribed resources {}
I0901 04:50:55.411201 783 master.cpp:7664] Sending 1 offers to framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 (default)
I0901 04:50:55.411216 780 hierarchical.cpp:2033] No inverse offers to send out!
I0901 04:50:55.411837 780 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.349304ms
I0901 04:50:55.411903 783 master.cpp:6685] Received update of agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 at slave(740)@172.17.0.4:33678 (92967e954293) with total oversubscribed resources {}
I0901 04:50:55.412232 783 master.cpp:6685] Received update of agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 at slave(740)@172.17.0.4:33678 (92967e954293) with total oversubscribed resources {}
I0901 04:50:55.412287 795 hierarchical.cpp:660] Agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 (92967e954293) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0901 04:50:55.412653 783 hierarchical.cpp:660] Agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 (92967e954293) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0901 04:50:55.413357 775 scheduler.cpp:676] Enqueuing event OFFERS received from http://172.17.0.4:33678/master/api/v1/scheduler
I0901 04:50:55.416229 788 scheduler.cpp:243] Sending ACCEPT call to http://172.17.0.4:33678/master/api/v1/scheduler
I0901 04:50:55.417367 784 process.cpp:3956] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0901 04:50:55.419214 775 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:38630
I0901 04:50:55.420660 775 master.cpp:9162] Removing offer 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-O0
I0901 04:50:55.420846 775 master.cpp:4156] Processing ACCEPT call for offers: [ 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-O0 ] on agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 at slave(740)@172.17.0.4:33678 (92967e954293) for framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 (default)
I0901 04:50:55.420967 775 master.cpp:3533] Authorizing framework principal 'test-principal' to launch task 6814fae1-6301-4cb5-a4a9-3e537db13e71
W0901 04:50:55.423959 775 validation.cpp:1332] Executor 'default' for task '6814fae1-6301-4cb5-a4a9-3e537db13e71' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0901 04:50:55.424012 775 validation.cpp:1344] Executor 'default' for task '6814fae1-6301-4cb5-a4a9-3e537db13e71' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0901 04:50:55.424649 775 master.cpp:9908] Adding task 6814fae1-6301-4cb5-a4a9-3e537db13e71 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 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 at slave(740)@172.17.0.4:33678 (92967e954293)
I0901 04:50:55.425324 775 master.cpp:4819] Launching task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-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 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 at slave(740)@172.17.0.4:33678 (92967e954293)
I0901 04:50:55.426069 793 slave.cpp:1744] Got assigned task '6814fae1-6301-4cb5-a4a9-3e537db13e71' for framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.426921 793 slave.cpp:2012] Authorizing task '6814fae1-6301-4cb5-a4a9-3e537db13e71' for framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.426985 793 slave.cpp:6795] Authorizing framework principal 'test-principal' to launch task 6814fae1-6301-4cb5-a4a9-3e537db13e71
I0901 04:50:55.427690 787 hierarchical.cpp:887] Updated allocation of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 on agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-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]
I0901 04:50:55.428354 790 slave.cpp:2180] Launching task '6814fae1-6301-4cb5-a4a9-3e537db13e71' for framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.429177 790 paths.cpp:578] Trying to chown '/tmp/ContentType_SchedulerTest_KillTask_0_TmVaxn/slaves/4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0/frameworks/4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000/executors/default/runs/d3b39a86-4644-4d55-a09d-e2b5d50fc197' to user 'mesos'
I0901 04:50:55.429442 790 slave.cpp:7257] Launching executor 'default' of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 with resources [] in work directory '/tmp/ContentType_SchedulerTest_KillTask_0_TmVaxn/slaves/4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0/frameworks/4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000/executors/default/runs/d3b39a86-4644-4d55-a09d-e2b5d50fc197'
I0901 04:50:55.430404 790 slave.cpp:2408] Queued task '6814fae1-6301-4cb5-a4a9-3e537db13e71' for executor 'default' of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.430785 790 slave.cpp:2871] Launching container d3b39a86-4644-4d55-a09d-e2b5d50fc197 for executor 'default' of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.431218 795 slave.cpp:940] Successfully attached file '/tmp/ContentType_SchedulerTest_KillTask_0_TmVaxn/slaves/4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0/frameworks/4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000/executors/default/runs/d3b39a86-4644-4d55-a09d-e2b5d50fc197'
I0901 04:50:55.431371 776 slave.cpp:940] Successfully attached file '/tmp/ContentType_SchedulerTest_KillTask_0_TmVaxn/slaves/4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0/frameworks/4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000/executors/default/runs/d3b39a86-4644-4d55-a09d-e2b5d50fc197'
I0901 04:50:55.433974 790 executor.cpp:192] Version: 1.5.0
W0901 04:50:55.434034 790 process.cpp:3240] Attempted to spawn already running process version@172.17.0.4:33678
I0901 04:50:55.436367 789 executor.cpp:410] Connected with the agent
I0901 04:50:55.437168 787 executor.cpp:307] Sending SUBSCRIBE call to http://172.17.0.4:33678/slave(740)/api/v1/executor
I0901 04:50:55.438124 776 process.cpp:3956] Handling HTTP event for process 'slave(740)' with path: '/slave(740)/api/v1/executor'
I0901 04:50:55.440877 792 http.cpp:1185] HTTP POST for /slave(740)/api/v1/executor from 172.17.0.4:38632
I0901 04:50:55.441172 792 slave.cpp:3740] Received Subscribe request for HTTP executor 'default' of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.442383 792 slave.cpp:2610] Sending queued task '6814fae1-6301-4cb5-a4a9-3e537db13e71' to executor 'default' of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 (via HTTP)
I0901 04:50:55.443969 780 executor.cpp:725] Enqueuing event SUBSCRIBED received from http://172.17.0.4:33678/slave(740)/api/v1/executor
I0901 04:50:55.444759 776 executor.cpp:725] Enqueuing event LAUNCH received from http://172.17.0.4:33678/slave(740)/api/v1/executor
I0901 04:50:55.445453 777 executor.cpp:307] Sending UPDATE call to http://172.17.0.4:33678/slave(740)/api/v1/executor
I0901 04:50:55.447095 785 process.cpp:3956] Handling HTTP event for process 'slave(740)' with path: '/slave(740)/api/v1/executor'
I0901 04:50:55.449491 783 http.cpp:1185] HTTP POST for /slave(740)/api/v1/executor from 172.17.0.4:38634
I0901 04:50:55.449647 783 slave.cpp:4392] Handling status update TASK_RUNNING (UUID: c641637a-8d5a-41e2-9fd3-9b6407e60ac2) for task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.450318 794 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: c641637a-8d5a-41e2-9fd3-9b6407e60ac2) for task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.450356 794 status_update_manager.cpp:500] Creating StatusUpdate stream for task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.450865 794 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: c641637a-8d5a-41e2-9fd3-9b6407e60ac2) for task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 to the agent
I0901 04:50:55.451063 776 slave.cpp:4873] Forwarding the update TASK_RUNNING (UUID: c641637a-8d5a-41e2-9fd3-9b6407e60ac2) for task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 to master@172.17.0.4:33678
I0901 04:50:55.451263 776 slave.cpp:4767] Status update manager successfully handled status update TASK_RUNNING (UUID: c641637a-8d5a-41e2-9fd3-9b6407e60ac2) for task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.451529 774 master.cpp:6843] Status update TASK_RUNNING (UUID: c641637a-8d5a-41e2-9fd3-9b6407e60ac2) for task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 from agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 at slave(740)@172.17.0.4:33678 (92967e954293)
I0901 04:50:55.451577 774 master.cpp:6905] Forwarding status update TASK_RUNNING (UUID: c641637a-8d5a-41e2-9fd3-9b6407e60ac2) for task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.451906 774 master.cpp:8930] Updating the state of task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0901 04:50:55.452042 797 executor.cpp:725] Enqueuing event ACKNOWLEDGED received from http://172.17.0.4:33678/slave(740)/api/v1/executor
I0901 04:50:55.453541 783 scheduler.cpp:676] Enqueuing event UPDATE received from http://172.17.0.4:33678/master/api/v1/scheduler
I0901 04:50:55.454480 781 scheduler.cpp:243] Sending ACKNOWLEDGE call to http://172.17.0.4:33678/master/api/v1/scheduler
I0901 04:50:55.454727 781 scheduler.cpp:243] Sending KILL call to http://172.17.0.4:33678/master/api/v1/scheduler
I0901 04:50:55.456071 788 process.cpp:3956] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0901 04:50:55.495409 787 process.cpp:3956] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0901 04:50:55.496156 787 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:38630
I0901 04:50:55.496408 787 master.cpp:5482] Processing ACKNOWLEDGE call c641637a-8d5a-41e2-9fd3-9b6407e60ac2 for task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 (default) on agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0
I0901 04:50:55.496884 777 status_update_manager.cpp:395] Received status update acknowledgement (UUID: c641637a-8d5a-41e2-9fd3-9b6407e60ac2) for task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.496985 778 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:38630
I0901 04:50:55.497128 779 slave.cpp:3676] Status update manager successfully handled status update acknowledgement (UUID: c641637a-8d5a-41e2-9fd3-9b6407e60ac2) for task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.497133 778 master.cpp:5300] Processing KILL call for task '6814fae1-6301-4cb5-a4a9-3e537db13e71' of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 (default)
I0901 04:50:55.497217 778 master.cpp:5374] Telling agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 at slave(740)@172.17.0.4:33678 (92967e954293) to kill task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 (default)
I0901 04:50:55.497375 785 slave.cpp:2944] Asked to kill task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.498123 792 executor.cpp:725] Enqueuing event KILL received from http://172.17.0.4:33678/slave(740)/api/v1/executor
I0901 04:50:55.498821 786 executor.cpp:307] Sending UPDATE call to http://172.17.0.4:33678/slave(740)/api/v1/executor
I0901 04:50:55.499617 774 process.cpp:3956] Handling HTTP event for process 'slave(740)' with path: '/slave(740)/api/v1/executor'
I0901 04:50:55.541204 775 http.cpp:1185] HTTP POST for /slave(740)/api/v1/executor from 172.17.0.4:38634
I0901 04:50:55.541401 775 slave.cpp:4392] Handling status update TASK_KILLED (UUID: 16655986-8536-4e34-9812-d3ec8b131285) for task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.542316 795 status_update_manager.cpp:323] Received status update TASK_KILLED (UUID: 16655986-8536-4e34-9812-d3ec8b131285) for task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.542433 795 status_update_manager.cpp:377] Forwarding update TASK_KILLED (UUID: 16655986-8536-4e34-9812-d3ec8b131285) for task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 to the agent
I0901 04:50:55.542615 776 slave.cpp:4873] Forwarding the update TASK_KILLED (UUID: 16655986-8536-4e34-9812-d3ec8b131285) for task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 to master@172.17.0.4:33678
I0901 04:50:55.542865 776 slave.cpp:4767] Status update manager successfully handled status update TASK_KILLED (UUID: 16655986-8536-4e34-9812-d3ec8b131285) for task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.543159 794 master.cpp:6843] Status update TASK_KILLED (UUID: 16655986-8536-4e34-9812-d3ec8b131285) for task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 from agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 at slave(740)@172.17.0.4:33678 (92967e954293)
I0901 04:50:55.543236 794 master.cpp:6905] Forwarding status update TASK_KILLED (UUID: 16655986-8536-4e34-9812-d3ec8b131285) for task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.543678 794 master.cpp:8930] Updating the state of task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0901 04:50:55.544178 777 executor.cpp:725] Enqueuing event ACKNOWLEDGED received from http://172.17.0.4:33678/slave(740)/api/v1/executor
I0901 04:50:55.544667 777 scheduler.cpp:676] Enqueuing event UPDATE received from http://172.17.0.4:33678/master/api/v1/scheduler
I0901 04:50:55.544862 791 hierarchical.cpp:1161] Recovered cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 from framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.546517 780 master.cpp:1435] Framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 (default) disconnected
I0901 04:50:55.546545 780 master.cpp:3267] Deactivating framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 (default)
I0901 04:50:55.546609 780 master.cpp:3244] Disconnecting framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 (default)
I0901 04:50:55.546658 780 master.cpp:1450] Giving framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 (default) 0ns to failover
I0901 04:50:55.546711 777 hierarchical.cpp:412] Deactivated framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.547005 784 master.cpp:7496] Framework failover timeout, removing framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 (default)
I0901 04:50:55.547029 784 master.cpp:8357] Removing framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 (default)
I0901 04:50:55.547149 789 slave.cpp:3208] Asked to shut down framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 by master@172.17.0.4:33678
I0901 04:50:55.547169 784 master.cpp:8930] Updating the state of task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0901 04:50:55.547183 789 slave.cpp:3233] Shutting down framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.547238 789 slave.cpp:5729] Shutting down executor 'default' of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 (via HTTP)
I0901 04:50:55.547188 784 master.cpp:9024] Removing task 6814fae1-6301-4cb5-a4a9-3e537db13e71 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"}] of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 on agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 at slave(740)@172.17.0.4:33678 (92967e954293)
I0901 04:50:55.547680 784 master.cpp:9053] Removing executor 'default' with resources [] of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 on agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 at slave(740)@172.17.0.4:33678 (92967e954293)
I0901 04:50:55.547868 787 executor.cpp:725] Enqueuing event SHUTDOWN received from http://172.17.0.4:33678/slave(740)/api/v1/executor
I0901 04:50:55.548274 790 hierarchical.cpp:355] Removed framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.549671 776 slave.cpp:5405] Executor 'default' of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 exited with status 0
I0901 04:50:55.549780 776 slave.cpp:5509] Cleaning up executor 'default' of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 (via HTTP)
W0901 04:50:55.549957 781 master.cpp:6960] Ignoring unknown exited executor 'default' of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000 on agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 at slave(740)@172.17.0.4:33678 (92967e954293)
I0901 04:50:55.550037 774 gc.cpp:91] Scheduling '/tmp/ContentType_SchedulerTest_KillTask_0_TmVaxn/slaves/4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0/frameworks/4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000/executors/default/runs/d3b39a86-4644-4d55-a09d-e2b5d50fc197' for gc 6.99999363501037days in the future
I0901 04:50:55.550248 776 slave.cpp:5605] Cleaning up framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.550259 786 gc.cpp:91] Scheduling '/tmp/ContentType_SchedulerTest_KillTask_0_TmVaxn/slaves/4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0/frameworks/4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000/executors/default' for gc 6.99999363242963days in the future
I0901 04:50:55.550354 788 status_update_manager.cpp:285] Closing status update streams for framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.550398 788 status_update_manager.cpp:531] Cleaning up status update stream for task 6814fae1-6301-4cb5-a4a9-3e537db13e71 of framework 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000
I0901 04:50:55.550421 786 gc.cpp:91] Scheduling '/tmp/ContentType_SchedulerTest_KillTask_0_TmVaxn/slaves/4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0/frameworks/4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-0000' for gc 6.99999363004741days in the future
I0901 04:50:55.550463 776 slave.cpp:859] Agent terminating
I0901 04:50:55.550667 793 master.cpp:1321] Agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 at slave(740)@172.17.0.4:33678 (92967e954293) disconnected
I0901 04:50:55.550688 793 master.cpp:3304] Disconnecting agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 at slave(740)@172.17.0.4:33678 (92967e954293)
I0901 04:50:55.550762 793 master.cpp:3323] Deactivating agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 at slave(740)@172.17.0.4:33678 (92967e954293)
I0901 04:50:55.550871 794 hierarchical.cpp:690] Agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0 deactivated
I0901 04:50:55.553644 782 master.cpp:1163] Master terminating
I0901 04:50:55.554168 790 hierarchical.cpp:626] Removed agent 4e9dee7a-354c-4f39-9ac7-9b9b213d75fb-S0
[ OK ] ContentType/SchedulerTest.KillTask/0 (190 ms)
[ RUN ] ContentType/SchedulerTest.KillTask/1
I0901 04:50:55.560899 773 cluster.cpp:162] Creating default 'local' authorizer
I0901 04:50:55.564478 783 master.cpp:445] Master d0697115-9ae7-4ed5-a394-a69ff55a8923 (92967e954293) started on 172.17.0.4:33678
I0901 04:50:55.564524 783 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/WtuXof/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" --work_dir="/tmp/WtuXof/master" --zk_session_timeout="10secs"
I0901 04:50:55.564893 783 master.cpp:497] Master only allowing authenticated frameworks to register
I0901 04:50:55.564903 783 master.cpp:511] Master only allowing authenticated agents to register
I0901 04:50:55.564910 783 master.cpp:524] Master only allowing authenticated HTTP frameworks to register
I0901 04:50:55.564920 783 credentials.hpp:37] Loading credentials for authentication from '/tmp/WtuXof/credentials'
I0901 04:50:55.565232 783 master.cpp:569] Using default 'crammd5' authenticator
I0901 04:50:55.565412 783 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0901 04:50:55.565594 783 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0901 04:50:55.565709 783 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0901 04:50:55.565815 783 master.cpp:649] Authorization enabled
I0901 04:50:55.565991 792 hierarchical.cpp:171] Initialized hierarchical allocator process
I0901 04:50:55.566002 789 whitelist_watcher.cpp:77] No whitelist given
I0901 04:50:55.569365 796 master.cpp:2166] Elected as the leading master!
I0901 04:50:55.569419 796 master.cpp:1705] Recovering from registrar
I0901 04:50:55.569576 779 registrar.cpp:347] Recovering registrar
I0901 04:50:55.570315 779 registrar.cpp:391] Successfully fetched the registry (0B) in 708864ns
I0901 04:50:55.570439 779 registrar.cpp:495] Applied 1 operations in 45327ns; attempting to update the registry
I0901 04:50:55.570996 779 registrar.cpp:552] Successfully updated the registry in 502016ns
*** Aborted at 1504241455 (unix time) try "date -d @1504241455" if you are using GNU date ***
I0901 04:50:55.571101 779 registrar.cpp:424] Successfully recovered registrar
I0901 04:50:55.571496 779 master.cpp:1804] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0901 04:50:55.571521 793 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
PC: @ 0x2b4f0af34c80 process::EventQueue::Consumer::empty()
*** SIGSEGV (@0x8) received by PID 773 (TID 0x2b4f17caa700) from PID 8; stack trace: ***
@ 0x2b4f0f452330 (unknown)
@ 0x2b4f0af34c80 process::EventQueue::Consumer::empty()
@ 0x2b4f0af18c20 process::ProcessManager::resume()
@ 0x2b4f0af27a71 process::ProcessManager::init_threads()::$_9::operator()()
@ 0x2b4f0af279b5 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvE3$_9vEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE
@ 0x2b4f0af27985 std::_Bind_simple<>::operator()()
@ 0x2b4f0af2795c std::thread::_Impl<>::_M_run()
@ 0x2b4f0f711a60 (unknown)
@ 0x2b4f0f44a184 start_thread
@ 0x2b4f0ff7dffd (unknown)
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-1504239222-5234
Untagged: mesos-1504239222-5234:latest
Deleted: sha256:43e76ae436d8802e4555542c6918dd66dd6a08252434215ffe7f85a12e1db9e2
Deleted: sha256:f772e9bde28c0a59eab1a05ad91e69689e7bc42698b80ce69074d5c2c3dec07b
Deleted: sha256:d4218fc383e3e0a99e4d9156567c013b9e764a9f77749e52d4c71e99677e8f9f
Deleted: sha256:f45cd03edc31641589e371c0f45f861eb7bc56c643c10bf1747661b5d872136f
Deleted: sha256:e833d911cf77177e686720081c9f2c49d2924a2c9b4e06070c3086575a00dfd2
Deleted: sha256:f68241c071f0e05b34ad283dc6b980fd0ef1b9c3b91a342e063a1cf652798f83
Deleted: sha256:df2ef62f66e8943c5843cfedce3fe777574eeacddc842a98263fceddc0feec7c
Deleted: sha256:ed39225dd695ebf3418b0376a64b949d0a3ee0e3c21bb35518570339219205ec
Deleted: sha256:2fff8b43f93a8a093b165474f02e6d8f177336ae5ce5c15d50dac51db75898c1
Deleted: sha256:5b9709c907cab67a642f78cb5d07304b9689844716c26deb0f6ac794c0e1439d
Deleted: sha256:ae4b395ced060227767b41ebcca5c80ddefe5b9284c31c1f360e86768ce7472f
Deleted: sha256:3be647e78ffdd28b7e39e1b2c176f815953d57a33b68dbfdf592fea16d61ffd2
Deleted: sha256:64ac9e331cba2b007af74d13d855095fd6465f3fd5483b7aa1ed7f7809f91a92
Deleted: sha256:6e1c80637ed2e47c3a18cda02561beebfb0bb4a10a9e2e9a2a7c1c621bc5b864
Deleted: sha256:e20fd12dbbaa76448ee13e91e6f6f8f71536b7679850b7c02d9e5aec6a11e1b1
Deleted: sha256:7f63688077321e9b988ab27b1b2fde9e269834d6309d60129139bc06a641ee22
Deleted: sha256:696245f457df878f31f6b679b8d7e8502833890c069759e17feea26a2e6eb429
Deleted: sha256:594dbd4e1ac318e058b541a1882fefff1b2e04e14991ef5289156a1f793b1780
Deleted: sha256:44f71740d72d9af6445bd6df8591ece082447401e69cb12dbb2172611b441f39
Deleted: sha256:db1f2e8d4d7f40ab5050bd8fb6f560e70fb5901f15f71fd29358ccd70c229f1e
Deleted: sha256:b70e9114168406bcbc44bc2f472786ed9894616d8aaf8df81144418f089a137c
Deleted: sha256:efb97cf0fc99dc8520870ae7ef762746002d5ca4117ba0fd94112f51447e5538
Deleted: sha256:3c07b7663ea7e83a598dd48764dddb4f91f820f184b06a4b97dab2c0fc9384d1
Deleted: sha256:b8d499c449bc2647cb6feb0564410b5a8a09c0f84adefb74a69bbf6b41a251aa
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user yujie.jay@gmail.com
Jenkins build is back to normal : Mesos-Buildbot » cmake,clang,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #4181
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/4181/display/redirect?page=changes>
Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #4180
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/4180/display/redirect?page=changes>
Changes:
[yujie.jay] Added an undiscardable() helper that blocks discards from propagating.
[yujie.jay] Used 'undiscardable' to protect a future in MesosContainerizer.
[yujie.jay] Added `is_specialization_of` trait.
------------------------------------------
[...truncated 20.88 MB...]
I0901 09:51:50.157801 754 master.cpp:2228] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0901 09:51:50.157974 761 state.cpp:64] Recovering state from '/tmp/ExecutorAuthorizationTest_FailedApiCalls_uw1XfX/meta'
I0901 09:51:50.158339 761 master.cpp:2681] Subscribing framework 'default' with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
I0901 09:51:50.158365 772 status_update_manager.cpp:203] Recovering status update manager
I0901 09:51:50.158772 756 slave.cpp:6295] Finished recovery
I0901 09:51:50.158999 761 master.hpp:2217] Sending heartbeat to cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000
I0901 09:51:50.159302 756 slave.cpp:6477] Querying resource estimator for oversubscribable resources
I0901 09:51:50.159561 772 status_update_manager.cpp:177] Pausing sending status updates
I0901 09:51:50.159559 752 slave.cpp:971] New master detected at master@172.17.0.4:39272
I0901 09:51:50.159590 758 hierarchical.cpp:303] Added framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000
I0901 09:51:50.159651 752 slave.cpp:1006] Detecting new master
I0901 09:51:50.159677 766 scheduler.cpp:676] Enqueuing event SUBSCRIBED received from http://172.17.0.4:39272/master/api/v1/scheduler
I0901 09:51:50.159747 752 slave.cpp:6491] Received oversubscribable resources {} from the resource estimator
I0901 09:51:50.159927 758 hierarchical.cpp:1925] No allocations performed
I0901 09:51:50.160084 766 scheduler.cpp:676] Enqueuing event HEARTBEAT received from http://172.17.0.4:39272/master/api/v1/scheduler
I0901 09:51:50.160084 758 hierarchical.cpp:2015] No inverse offers to send out!
I0901 09:51:50.160415 758 hierarchical.cpp:1468] Performed allocation for 0 agents in 536731ns
I0901 09:51:50.170518 765 slave.cpp:1033] Authenticating with master master@172.17.0.4:39272
I0901 09:51:50.170662 765 slave.cpp:1044] Using default CRAM-MD5 authenticatee
I0901 09:51:50.170954 771 authenticatee.cpp:121] Creating new client SASL connection
I0901 09:51:50.171298 752 master.cpp:7832] Authenticating slave(537)@172.17.0.4:39272
I0901 09:51:50.171448 759 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1079)@172.17.0.4:39272
I0901 09:51:50.171748 767 authenticator.cpp:98] Creating new server SASL connection
I0901 09:51:50.171933 758 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0901 09:51:50.171959 758 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0901 09:51:50.172063 764 authenticator.cpp:204] Received SASL authentication start
I0901 09:51:50.172124 764 authenticator.cpp:326] Authentication requires more steps
I0901 09:51:50.172211 761 authenticatee.cpp:259] Received SASL authentication step
I0901 09:51:50.172303 764 authenticator.cpp:232] Received SASL authentication step
I0901 09:51:50.172327 764 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5904d5bfedc1' server FQDN: '5904d5bfedc1' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0901 09:51:50.172339 764 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0901 09:51:50.172382 764 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0901 09:51:50.172399 764 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5904d5bfedc1' server FQDN: '5904d5bfedc1' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0901 09:51:50.172408 764 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0901 09:51:50.172415 764 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0901 09:51:50.172430 764 authenticator.cpp:318] Authentication success
I0901 09:51:50.172497 773 authenticatee.cpp:299] Authentication success
I0901 09:51:50.172674 760 master.cpp:7862] Successfully authenticated principal 'test-principal' at slave(537)@172.17.0.4:39272
I0901 09:51:50.172745 751 slave.cpp:1128] Successfully authenticated with master master@172.17.0.4:39272
I0901 09:51:50.172760 760 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1079)@172.17.0.4:39272
I0901 09:51:50.172981 751 slave.cpp:1607] Will retry registration in 4.628387ms if necessary
I0901 09:51:50.173152 769 master.cpp:5714] Received register agent message from slave(537)@172.17.0.4:39272 (5904d5bfedc1)
I0901 09:51:50.173188 769 master.cpp:3803] Authorizing agent with principal 'test-principal'
I0901 09:51:50.173630 770 master.cpp:5774] Authorized registration of agent at slave(537)@172.17.0.4:39272 (5904d5bfedc1)
I0901 09:51:50.173738 770 master.cpp:5867] Registering agent at slave(537)@172.17.0.4:39272 (5904d5bfedc1) with id cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0
I0901 09:51:50.174201 757 registrar.cpp:495] Applied 1 operations in 80812ns; attempting to update the registry
I0901 09:51:50.174829 752 registrar.cpp:552] Successfully updated the registry in 555008ns
I0901 09:51:50.175019 767 master.cpp:5914] Admitted agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0 at slave(537)@172.17.0.4:39272 (5904d5bfedc1)
I0901 09:51:50.175586 758 slave.cpp:4970] Received ping from slave-observer(528)@172.17.0.4:39272
I0901 09:51:50.175776 758 slave.cpp:1174] Registered with master master@172.17.0.4:39272; given agent ID cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0
I0901 09:51:50.175626 767 master.cpp:5945] Registered agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0 at slave(537)@172.17.0.4:39272 (5904d5bfedc1) 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"}]
I0901 09:51:50.175958 767 status_update_manager.cpp:184] Resuming sending status updates
I0901 09:51:50.175992 761 hierarchical.cpp:593] Added agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0 (5904d5bfedc1) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0901 09:51:50.176182 758 slave.cpp:1194] Checkpointing SlaveInfo to '/tmp/ExecutorAuthorizationTest_FailedApiCalls_uw1XfX/meta/slaves/cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0/slave.info'
I0901 09:51:50.176501 758 slave.cpp:1243] Forwarding total oversubscribed resources {}
I0901 09:51:50.176625 758 master.cpp:6683] Received update of agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0 at slave(537)@172.17.0.4:39272 (5904d5bfedc1) with total oversubscribed resources {}
I0901 09:51:50.177279 761 hierarchical.cpp:2015] No inverse offers to send out!
I0901 09:51:50.177328 761 hierarchical.cpp:1468] Performed allocation for 1 agents in 1.188712ms
I0901 09:51:50.177505 761 hierarchical.cpp:660] Agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0 (5904d5bfedc1) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0901 09:51:50.177762 754 master.cpp:7662] Sending 1 offers to framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 (default)
I0901 09:51:50.179508 751 scheduler.cpp:676] Enqueuing event OFFERS received from http://172.17.0.4:39272/master/api/v1/scheduler
I0901 09:51:50.181243 752 scheduler.cpp:243] Sending ACCEPT call to http://172.17.0.4:39272/master/api/v1/scheduler
I0901 09:51:50.182265 759 process.cpp:3933] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0901 09:51:50.183375 753 http.cpp:1166] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:49316
I0901 09:51:50.184298 753 master.cpp:9159] Removing offer cea321b7-b475-40e1-a7c8-adb8a90c82ea-O0
I0901 09:51:50.184504 753 master.cpp:4153] Processing ACCEPT call for offers: [ cea321b7-b475-40e1-a7c8-adb8a90c82ea-O0 ] on agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0 at slave(537)@172.17.0.4:39272 (5904d5bfedc1) for framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 (default)
I0901 09:51:50.184653 753 master.cpp:3530] Authorizing framework principal 'test-principal' to launch task a8d258a7-3672-40a7-8fba-83aa2f80b3d9
I0901 09:51:50.188060 770 master.cpp:9719] Adding task a8d258a7-3672-40a7-8fba-83aa2f80b3d9 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":32.0},"type":"SCALAR"}] on agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0 at slave(537)@172.17.0.4:39272 (5904d5bfedc1)
I0901 09:51:50.188450 770 master.cpp:5020] Launching task group { a8d258a7-3672-40a7-8fba-83aa2f80b3d9 } of framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 (default) with resources cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 on agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0 at slave(537)@172.17.0.4:39272 (5904d5bfedc1)
I0901 09:51:50.188956 767 slave.cpp:1736] Got assigned task group containing tasks [ a8d258a7-3672-40a7-8fba-83aa2f80b3d9 ] for framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000
I0901 09:51:50.189712 758 hierarchical.cpp:1152] Recovered cpus(allocated: *):1.8; mem(allocated: *):960; disk(allocated: *):960; ports(allocated: *):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: cpus(allocated: *):0.2; mem(allocated: *):64; disk(allocated: *):64) on agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0 from framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000
I0901 09:51:50.189795 758 hierarchical.cpp:1189] Framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 filtered agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0 for 5secs
I0901 09:51:50.189929 767 slave.cpp:2003] Authorizing task group containing tasks [ a8d258a7-3672-40a7-8fba-83aa2f80b3d9 ] for framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000
I0901 09:51:50.190013 767 slave.cpp:6794] Authorizing framework principal 'test-principal' to launch task a8d258a7-3672-40a7-8fba-83aa2f80b3d9
I0901 09:51:50.190915 763 slave.cpp:2171] Launching task group containing tasks [ a8d258a7-3672-40a7-8fba-83aa2f80b3d9 ] for framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000
I0901 09:51:50.191567 763 paths.cpp:578] Trying to chown '/tmp/ExecutorAuthorizationTest_FailedApiCalls_uw1XfX/slaves/cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0/frameworks/cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000/executors/default/runs/cafd571b-bc42-43c8-b971-484c6a8ed0ea' to user 'mesos'
I0901 09:51:50.191819 763 slave.cpp:7256] Launching executor 'default' of framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":32.0},"type":"SCALAR"}] in work directory '/tmp/ExecutorAuthorizationTest_FailedApiCalls_uw1XfX/slaves/cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0/frameworks/cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000/executors/default/runs/cafd571b-bc42-43c8-b971-484c6a8ed0ea'
I0901 09:51:50.192566 763 slave.cpp:2400] Queued task group containing tasks [ a8d258a7-3672-40a7-8fba-83aa2f80b3d9 ] for executor 'default' of framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000
I0901 09:51:50.192680 763 slave.cpp:924] Successfully attached file '/tmp/ExecutorAuthorizationTest_FailedApiCalls_uw1XfX/slaves/cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0/frameworks/cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000/executors/default/runs/cafd571b-bc42-43c8-b971-484c6a8ed0ea'
I0901 09:51:50.192731 763 slave.cpp:924] Successfully attached file '/tmp/ExecutorAuthorizationTest_FailedApiCalls_uw1XfX/slaves/cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0/frameworks/cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000/executors/default/runs/cafd571b-bc42-43c8-b971-484c6a8ed0ea'
I0901 09:51:50.192994 763 slave.cpp:2858] Launching container cafd571b-bc42-43c8-b971-484c6a8ed0ea for executor 'default' of framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000
I0901 09:51:50.195657 768 executor.cpp:192] Version: 1.4.0
I0901 09:51:50.195710 768 process.cpp:3228] Attempting to spawn already spawned process version@172.17.0.4:39272
I0901 09:51:50.197906 770 executor.cpp:410] Connected with the agent
I0901 09:51:50.198882 754 executor.cpp:307] Sending SUBSCRIBE call to http://172.17.0.4:39272/slave(537)/api/v1/executor
I0901 09:51:50.199646 768 process.cpp:3933] Handling HTTP event for process 'slave(537)' with path: '/slave(537)/api/v1/executor'
I0901 09:51:50.201894 770 http.cpp:1166] HTTP POST for /slave(537)/api/v1/executor from 172.17.0.4:49318
I0901 09:51:50.202117 770 slave.cpp:3727] Received Subscribe request for HTTP executor 'default' of framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000
I0901 09:51:50.203369 770 slave.cpp:2634] Sending queued task group task group containing tasks [ a8d258a7-3672-40a7-8fba-83aa2f80b3d9 ] to executor 'default' of framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 (via HTTP)
I0901 09:51:50.208879 763 executor.cpp:725] Enqueuing event SUBSCRIBED received from http://172.17.0.4:39272/slave(537)/api/v1/executor
I0901 09:51:50.209988 770 executor.cpp:725] Enqueuing event LAUNCH_GROUP received from http://172.17.0.4:39272/slave(537)/api/v1/executor
I0901 09:51:50.212977 753 process.cpp:3933] Handling HTTP event for process 'slave(537)' with path: '/slave(537)/api/v1'
I0901 09:51:50.214624 754 http.cpp:1166] HTTP POST for /slave(537)/api/v1 from 172.17.0.4:49322
I0901 09:51:50.215067 754 http.cpp:532] Processing call LAUNCH_NESTED_CONTAINER
I0901 09:51:50.217780 772 process.cpp:3933] Handling HTTP event for process 'slave(537)' with path: '/slave(537)/api/v1'
I0901 09:51:50.219408 751 http.cpp:1166] HTTP POST for /slave(537)/api/v1 from 172.17.0.4:49324
I0901 09:51:50.219821 751 http.cpp:532] Processing call LAUNCH_NESTED_CONTAINER_SESSION
I0901 09:51:50.222450 757 process.cpp:3933] Handling HTTP event for process 'slave(537)' with path: '/slave(537)/api/v1'
I0901 09:51:50.224100 763 http.cpp:1166] HTTP POST for /slave(537)/api/v1 from 172.17.0.4:49326
I0901 09:51:50.224426 763 http.cpp:532] Processing call WAIT_NESTED_CONTAINER
I0901 09:51:50.226758 773 process.cpp:3933] Handling HTTP event for process 'slave(537)' with path: '/slave(537)/api/v1'
I0901 09:51:50.228592 764 http.cpp:1166] HTTP POST for /slave(537)/api/v1 from 172.17.0.4:49328
I0901 09:51:50.228883 764 http.cpp:532] Processing call KILL_NESTED_CONTAINER
I0901 09:51:50.231830 770 process.cpp:3933] Handling HTTP event for process 'slave(537)' with path: '/slave(537)/api/v1'
I0901 09:51:50.233976 771 http.cpp:1166] HTTP POST for /slave(537)/api/v1 from 172.17.0.4:49330
I0901 09:51:50.234408 771 http.cpp:532] Processing call REMOVE_NESTED_CONTAINER
I0901 09:51:50.237901 772 process.cpp:3933] Handling HTTP event for process 'slave(537)' with path: '/slave(537)/api/v1'
I0901 09:51:50.240113 752 http.cpp:1166] HTTP POST for /slave(537)/api/v1 from 172.17.0.4:49332
I0901 09:51:50.240614 752 http.cpp:532] Processing call ATTACH_CONTAINER_OUTPUT
I0901 09:51:50.244635 773 process.cpp:3933] Handling HTTP event for process 'slave(537)' with path: '/slave(537)/api/v1/executor'
I0901 09:51:50.247131 766 http.cpp:1166] HTTP POST for /slave(537)/api/v1/executor from 172.17.0.4:49334
I0901 09:51:50.254104 752 process.cpp:3933] Handling HTTP event for process 'slave(537)' with path: '/slave(537)/api/v1/executor'
I0901 09:51:50.256498 767 http.cpp:1166] HTTP POST for /slave(537)/api/v1/executor from 172.17.0.4:49336
I0901 09:51:50.259264 767 master.cpp:1432] Framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 (default) disconnected
I0901 09:51:50.259296 767 master.cpp:3264] Deactivating framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 (default)
I0901 09:51:50.259372 767 master.cpp:3241] Disconnecting framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 (default)
I0901 09:51:50.259402 767 master.cpp:1447] Giving framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 (default) 0ns to failover
I0901 09:51:50.259438 766 hierarchical.cpp:412] Deactivated framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000
I0901 09:51:50.259758 764 master.cpp:7494] Framework failover timeout, removing framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 (default)
I0901 09:51:50.259775 764 master.cpp:8355] Removing framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 (default)
I0901 09:51:50.259971 764 master.cpp:8928] Updating the state of task a8d258a7-3672-40a7-8fba-83aa2f80b3d9 of framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0901 09:51:50.260542 760 slave.cpp:3195] Asked to shut down framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 by master@172.17.0.4:39272
I0901 09:51:50.260574 760 slave.cpp:3220] Shutting down framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000
I0901 09:51:50.260627 760 slave.cpp:5736] Shutting down executor 'default' of framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 (via HTTP)
I0901 09:51:50.260498 764 master.cpp:9022] Removing task a8d258a7-3672-40a7-8fba-83aa2f80b3d9 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":32.0},"type":"SCALAR"}] of framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 on agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0 at slave(537)@172.17.0.4:39272 (5904d5bfedc1)
I0901 09:51:50.260969 764 master.cpp:9051] Removing executor 'default' with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":32.0},"type":"SCALAR"}] of framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 on agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0 at slave(537)@172.17.0.4:39272 (5904d5bfedc1)
I0901 09:51:50.261302 760 hierarchical.cpp:1152] Recovered cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32) on agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0 from framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000
I0901 09:51:50.261893 760 hierarchical.cpp:1152] Recovered cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0 from framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000
I0901 09:51:50.262174 760 hierarchical.cpp:355] Removed framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000
I0901 09:51:50.262804 758 executor.cpp:725] Enqueuing event SHUTDOWN received from http://172.17.0.4:39272/slave(537)/api/v1/executor
I0901 09:51:50.263916 768 slave.cpp:5412] Executor 'default' of framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 exited with status 0
*** Aborted at 1504259510 (unix time) try "date -d @1504259510" if you are using GNU date ***
I0901 09:51:50.264150 768 slave.cpp:5516] Cleaning up executor 'default' of framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 (via HTTP)
W0901 09:51:50.264273 771 master.cpp:6958] Ignoring unknown exited executor 'default' of framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000 on agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0 at slave(537)@172.17.0.4:39272 (5904d5bfedc1)
I0901 09:51:50.264437 764 gc.cpp:59] Scheduling '/tmp/ExecutorAuthorizationTest_FailedApiCalls_uw1XfX/slaves/cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0/frameworks/cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000/executors/default/runs/cafd571b-bc42-43c8-b971-484c6a8ed0ea' for gc 6.99999694117926days in the future
I0901 09:51:50.264528 768 slave.cpp:5612] Cleaning up framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000
I0901 09:51:50.264595 764 gc.cpp:59] Scheduling '/tmp/ExecutorAuthorizationTest_FailedApiCalls_uw1XfX/slaves/cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0/frameworks/cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000/executors/default' for gc 6.9999969391437days in the future
I0901 09:51:50.264621 751 status_update_manager.cpp:285] Closing status update streams for framework cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000
I0901 09:51:50.264725 764 gc.cpp:59] Scheduling '/tmp/ExecutorAuthorizationTest_FailedApiCalls_uw1XfX/slaves/cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0/frameworks/cea321b7-b475-40e1-a7c8-adb8a90c82ea-0000' for gc 6.99999693716444days in the future
I0901 09:51:50.274735 749 slave.cpp:843] Agent terminating
PC: @ 0x3592ce8 testing::internal::UntypedFunctionMockerBase::UntypedInvokeWith()
*** SIGSEGV (@0x30) received by PID 749 (TID 0x2aea73f98700) from PID 48; stack trace: ***
@ 0x2aea6952f330 (unknown)
I0901 09:51:50.278408 760 master.cpp:1318] Agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0 at slave(537)@172.17.0.4:39272 (5904d5bfedc1) disconnected
I0901 09:51:50.278455 760 master.cpp:3301] Disconnecting agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0 at slave(537)@172.17.0.4:39272 (5904d5bfedc1)
I0901 09:51:50.278532 760 master.cpp:3320] Deactivating agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0 at slave(537)@172.17.0.4:39272 (5904d5bfedc1)
I0901 09:51:50.278674 771 hierarchical.cpp:690] Agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0 deactivated
I0901 09:51:50.288210 749 master.cpp:1160] Master terminating
@ 0x3592ce8 testing::internal::UntypedFunctionMockerBase::UntypedInvokeWith()
I0901 09:51:50.289286 758 hierarchical.cpp:626] Removed agent cea321b7-b475-40e1-a7c8-adb8a90c82ea-S0
@ 0x1c9eaf9 _ZN7testing8internal18FunctionMockerBaseIFvPN5mesos2v18executor5MesosEEE10InvokeWithERKSt5tupleIJS6_EE
[ OK ] ExecutorAuthorizationTest.FailedApiCalls (171 ms)
[----------] 3 tests from ExecutorAuthorizationTest (750 ms total)
[----------] 3 tests from SlaveStateTest
[ RUN ] SlaveStateTest.CheckpointString
[ OK ] SlaveStateTest.CheckpointString (0 ms)
[ RUN ] SlaveStateTest.CheckpointProtobufMessage
[ OK ] SlaveStateTest.CheckpointProtobufMessage (1 ms)
[ RUN ] SlaveStateTest.CheckpointRepeatedProtobufMessages
[ OK ] SlaveStateTest.CheckpointRepeatedProtobufMessages (1 ms)
[----------] 3 tests from SlaveStateTest (2 ms total)
[----------] 28 tests from SlaveRecoveryTest/0, where TypeParam = mesos::internal::slave::MesosContainerizer
[ RUN ] SlaveRecoveryTest/0.RecoverSlaveState
@ 0x1c9ead8 testing::internal::FunctionMocker<>::Invoke()
I0901 09:51:50.307230 749 cluster.cpp:162] Creating default 'local' authorizer
I0901 09:51:50.312767 756 master.cpp:442] Master f28969b0-6f22-46ee-a4ad-a619bc001334 (5904d5bfedc1) started on 172.17.0.4:39272
I0901 09:51:50.312813 756 master.cpp:444] 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/nUFBN9/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" --work_dir="/tmp/nUFBN9/master" --zk_session_timeout="10secs"
I0901 09:51:50.313136 756 master.cpp:494] Master only allowing authenticated frameworks to register
I0901 09:51:50.313148 756 master.cpp:508] Master only allowing authenticated agents to register
I0901 09:51:50.313158 756 master.cpp:521] Master only allowing authenticated HTTP frameworks to register
I0901 09:51:50.313166 756 credentials.hpp:37] Loading credentials for authentication from '/tmp/nUFBN9/credentials'
I0901 09:51:50.313454 756 master.cpp:566] Using default 'crammd5' authenticator
I0901 09:51:50.313612 756 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0901 09:51:50.313773 756 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0901 09:51:50.313882 756 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0901 09:51:50.313985 756 master.cpp:646] Authorization enabled
I0901 09:51:50.314155 772 whitelist_watcher.cpp:77] No whitelist given
@ 0x1c9e52a mesos::internal::tests::executor::MockHTTPExecutor<>::shutdown()
I0901 09:51:50.320425 751 hierarchical.cpp:171] Initialized hierarchical allocator process
I0901 09:51:50.320710 758 master.cpp:2163] Elected as the leading master!
I0901 09:51:50.320731 758 master.cpp:1702] Recovering from registrar
I0901 09:51:50.320894 757 registrar.cpp:347] Recovering registrar
I0901 09:51:50.321666 757 registrar.cpp:391] Successfully fetched the registry (0B) in 713984ns
I0901 09:51:50.321802 757 registrar.cpp:495] Applied 1 operations in 49051ns; attempting to update the registry
I0901 09:51:50.322721 765 registrar.cpp:552] Successfully updated the registry in 851968ns
I0901 09:51:50.325358 765 registrar.cpp:424] Successfully recovered registrar
I0901 09:51:50.326011 769 master.cpp:1801] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0901 09:51:50.326119 769 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
@ 0x1c9df70 mesos::internal::tests::executor::MockHTTPExecutor<>::event()
@ 0x1c9bb9e mesos::internal::tests::executor::TestMesos<>::events()
@ 0x1c9c2dc _ZNKSt7_Mem_fnIMN5mesos8internal5tests8executor9TestMesosINS0_2v18executor5MesosENS6_5EventEEEFvSt5queueIS8_St5dequeIS8_SaIS8_EEEEEclIJRKSE_EvEEvPS9_DpOT_
@ 0x1c9c150 _ZNSt5_BindIFSt7_Mem_fnIMN5mesos8internal5tests8executor9TestMesosINS1_2v18executor5MesosENS7_5EventEEEFvSt5queueIS9_St5dequeIS9_SaIS9_EEEEEPSA_St12_PlaceholderILi1EEEE6__callIvJRKSF_EJLm0ELm1EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
@ 0x1c9c0bd _ZNSt5_BindIFSt7_Mem_fnIMN5mesos8internal5tests8executor9TestMesosINS1_2v18executor5MesosENS7_5EventEEEFvSt5queueIS9_St5dequeIS9_SaIS9_EEEEEPSA_St12_PlaceholderILi1EEEEclIJRKSF_EvEET0_DpOT_
@ 0x1c9be72 std::_Function_handler<>::_M_invoke()
@ 0x2aea62701808 std::function<>::operator()()
@ 0x2aea627000ad process::AsyncExecutorProcess::execute<>()
@ 0x2aea62700e5e _ZZN7process8dispatchI7NothingNS_20AsyncExecutorProcessERKSt8functionIFvRKSt5queueIN5mesos2v18executor5EventESt5dequeIS8_SaIS8_EEEEESC_SI_RSC_EENS_6FutureIT_EERKNS_3PIDIT0_EEMSO_FSL_T1_T2_EOT3_OT4_ENKUlRSG_SJ_PNS_11ProcessBaseEE_clES10_SJ_S12_
@ 0x2aea62700c79 _ZNSt5_BindIFZN7process8dispatchI7NothingNS0_20AsyncExecutorProcessERKSt8functionIFvRKSt5queueIN5mesos2v18executor5EventESt5dequeIS9_SaIS9_EEEEESD_SJ_RSD_EENS0_6FutureIT_EERKNS0_3PIDIT0_EEMSP_FSM_T1_T2_EOT3_OT4_EUlRSH_SK_PNS0_11ProcessBaseEE_SH_SD_St12_PlaceholderILi1EEEE6__callIvJOS13_EJLm0ELm1ELm2EEEESM_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
@ 0x2aea62700bcd _ZNSt5_BindIFZN7process8dispatchI7NothingNS0_20AsyncExecutorProcessERKSt8functionIFvRKSt5queueIN5mesos2v18executor5EventESt5dequeIS9_SaIS9_EEEEESD_SJ_RSD_EENS0_6FutureIT_EERKNS0_3PIDIT0_EEMSP_FSM_T1_T2_EOT3_OT4_EUlRSH_SK_PNS0_11ProcessBaseEE_SH_SD_St12_PlaceholderILi1EEEEclIJS13_EvEESP_DpOT_
@ 0x2aea627006d2 _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEESt5_BindIFZNS0_8dispatchI7NothingNS0_20AsyncExecutorProcessERKSt8functionIFvRKSt5queueIN5mesos2v18executor5EventESt5dequeISD_SaISD_EEEEESH_SN_RSH_EENS0_6FutureIT_EERKNS0_3PIDIT0_EEMST_FSQ_T1_T2_EOT3_OT4_EUlRSL_SO_S2_E_SL_SH_St12_PlaceholderILi1EEEEE9_M_invokeERKSt9_Any_dataS2_
@ 0x2aea65027da8 std::function<>::operator()()
@ 0x2aea6500bf74 process::ProcessBase::visit()
@ 0x2aea650b0f8e process::DispatchEvent::visit()
@ 0x1bd6911 process::ProcessBase::serve()
@ 0x2aea65009d1f process::ProcessManager::resume()
@ 0x2aea65018891 process::ProcessManager::init_threads()::$_9::operator()()
@ 0x2aea650187d5 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvE3$_9vEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE
@ 0x2aea650187a5 std::_Bind_simple<>::operator()()
@ 0x2aea6501877c std::thread::_Impl<>::_M_run()
@ 0x2aea697eea60 (unknown)
@ 0x2aea69527184 start_thread
@ 0x2aea6a05affd (unknown)
make[3]: Leaving directory `/mesos/build'
make[3]: *** [CMakeFiles/check] Segmentation fault (core dumped)
make[2]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1504257431-28895
Untagged: mesos-1504257431-28895:latest
Deleted: sha256:8db9dd937098346623590815fd1c736eea3f09138f8d17348c178d47cc927550
Deleted: sha256:684adbde48fa0e6610399d9c77f9fe9d1f3ee807c7e946cbeac2fb61cc05b82e
Deleted: sha256:f1d6466950c9c686f79c9342f1d6aab473dc2d2d4571b6bffebf1f8fb42dd339
Deleted: sha256:645922929f37e129913070673aa7abce7e37f5b882d7cde9dde8f4b95c52007c
Deleted: sha256:cb442f7458f449b9c7e770320f07cb802496e9bebf826faeefe5d0293e3d8a05
Deleted: sha256:116a207e4d147541beb97a0253f603458cfb40d8ac34920058cfacbe9feb4e6c
Deleted: sha256:144ec63c930e13d5655a9736af2655ca9cc333c734558b086025fec448a69f80
Deleted: sha256:6ceec3548675a82e0adffad9891d6b21e8f2413f6c619c33ca1aa28e0514fe82
Deleted: sha256:202d48c6543e66e26d9b01621ab2ae9488f757a4b962fb1b0b0cb22f640b0c27
Deleted: sha256:061b737bb04562a4f1a5395af3be37c06cdbd772c9bb03c8f2d1c59f06c0bd8d
Deleted: sha256:2c44c411031661d86cdfcdff590f0e691bfd62df934ed22e8e5ebebad2305660
Deleted: sha256:0e63f8ebe878222023f10a84bcdeaed6805c1bef0131206075cd61b65ffbbf8e
Deleted: sha256:8a2f2399061cb432462de2a4ae7e511c093af7ea9573a57d7e71ba1a3291a4cf
Deleted: sha256:d3141a71d943f5c06f0233dac0c61ace563badd05c0430487d1284ac6b278636
Deleted: sha256:14d5fa2bf7bfe0e60f997e784d260c980e5fe70a0a13e4b955f0fac30dc8893d
Deleted: sha256:43e4ca2096616057b10ba389cf3333f9dfa835c97b826c7032ebd32eb4003db4
Deleted: sha256:f1d546df95361e27d48d9c43b13d0cf1dd8d35a3a535d70b6953f0c19baa4174
Deleted: sha256:7fcdb4fc7d7f89811837efcc54c230dbad25e053211c78ab93dfaccaf8b0418e
Deleted: sha256:4d4fcceccd53cc12ab1291914a56254241fa6b882b87d995c0f525d5536eca99
Deleted: sha256:87cc19a8d786e17df47a73358ed5072e30ecbee7176dd7b5e99e56191ad20267
Deleted: sha256:55e656b46f76f71a36a276cac2ebaa2093d888eebf9c75a355f0c4478175d2f6
Deleted: sha256:501e142cbd0c914b42b0270f01965211d4f409cce10ba54d2eca41a9fd67d21e
Deleted: sha256:97f78e3bb04c9def3ee4cffe5ad5296c7ba20069031afeb4e16bfa8bb8bf56ba
Deleted: sha256:5c88c805fa437c65e9033a1246017b99683fd89f783d343982b0d70cbe7904a5
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user yujie.jay@gmail.com