See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4570/display/redirect?page=changes>
Changes: [andrew] Windows: Modifed `os::write` to write binary files. [andrew] Added `os::copyfile(src, dst)` to stout. [andrew] Added `path::from_uri` and `uri::from_path` to stout. [andrew] Replaced manual URI creation with `uri::from_path()`. [andrew] Enabled HDFS compilation and associated tests. [andrew] Eliminated `os::shell()` calls from HDFS for Windows. [andrew] Ported `mesos-fetcher` to Windows. [andrew] Added `uri::FILE_PREFIX` constant for "file://". [andrew] Fixed bug where we didn't check non-localhost URIs were absolute. [andrew] Enabled `DefaultExecutorTest.TaskWithFileURI` on Windows. [andrew] Included `stout/os/ftruncate.hpp` to fix build failure. ------------------------------------------ [...truncated 34.27 MB...] 3: I1209 03:20:35.170593 16010 master.cpp:8879] Authenticating slave(1003)@172.17.0.4:40415 3: I1209 03:20:35.170785 16000 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1718)@172.17.0.4:40415 3: I1209 03:20:35.171124 16007 authenticator.cpp:98] Creating new server SASL connection 3: I1209 03:20:35.171432 16006 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1209 03:20:35.171463 16006 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1209 03:20:35.171562 16006 authenticator.cpp:204] Received SASL authentication start 3: I1209 03:20:35.171607 16006 authenticator.cpp:326] Authentication requires more steps 3: I1209 03:20:35.171692 16006 authenticatee.cpp:259] Received SASL authentication step 3: I1209 03:20:35.171828 16008 authenticator.cpp:232] Received SASL authentication step 3: I1209 03:20:35.171867 16008 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4dd79e931ff0' server FQDN: '4dd79e931ff0' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1209 03:20:35.171881 16008 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1209 03:20:35.171933 16008 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1209 03:20:35.171968 16008 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4dd79e931ff0' server FQDN: '4dd79e931ff0' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1209 03:20:35.171983 16008 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1209 03:20:35.171989 16008 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1209 03:20:35.172005 16008 authenticator.cpp:318] Authentication success 3: I1209 03:20:35.172168 16004 authenticatee.cpp:299] Authentication success 3: I1209 03:20:35.172224 15995 master.cpp:8909] Successfully authenticated principal 'test-principal' at slave(1003)@172.17.0.4:40415 3: I1209 03:20:35.172242 15999 hierarchical.cpp:1513] Performed allocation for 0 agents in 56824ns 3: I1209 03:20:35.172302 16005 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1718)@172.17.0.4:40415 3: I1209 03:20:35.172544 16009 slave.cpp:1207] Successfully authenticated with master master@172.17.0.4:40415 3: I1209 03:20:35.172821 16009 slave.cpp:1686] Will retry registration in 5.05806ms if necessary 3: I1209 03:20:35.173113 16010 master.cpp:6090] Received register agent message from slave(1003)@172.17.0.4:40415 (4dd79e931ff0) 3: I1209 03:20:35.173187 16010 master.cpp:3871] Authorizing agent with principal 'test-principal' 3: I1209 03:20:35.173753 16007 master.cpp:6166] Authorized registration of agent at slave(1003)@172.17.0.4:40415 (4dd79e931ff0) 3: I1209 03:20:35.173872 16007 master.cpp:6259] Registering agent at slave(1003)@172.17.0.4:40415 (4dd79e931ff0) with id f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 3: I1209 03:20:35.174343 16002 registrar.cpp:495] Applied 1 operations in 128311ns; attempting to update the registry 3: I1209 03:20:35.175031 16002 registrar.cpp:552] Successfully updated the registry in 627968ns 3: I1209 03:20:35.175268 15995 master.cpp:6308] Admitted agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 at slave(1003)@172.17.0.4:40415 (4dd79e931ff0) 3: I1209 03:20:35.176054 15995 master.cpp:6344] Registered agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 at slave(1003)@172.17.0.4:40415 (4dd79e931ff0) with cpus:1; mem:512; disk:3701220; ports:[31000-32000] 3: I1209 03:20:35.176136 15999 slave.cpp:1253] Registered with master master@172.17.0.4:40415; given agent ID f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 3: I1209 03:20:35.176256 15995 task_status_update_manager.cpp:188] Resuming sending task status updates 3: I1209 03:20:35.176611 15999 slave.cpp:1273] Checkpointing SlaveInfo to '/tmp/ResourceProviderCapability_ReservationTest_PreventUnreservingAlienResources_1_HCGx3D/meta/slaves/f8f42914-2de0-41a9-85f8-f86d267dbb86-S0/slave.info' 3: I1209 03:20:35.176602 16003 hierarchical.cpp:572] Added agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 (4dd79e931ff0) with cpus:1; mem:512; disk:3701220; ports:[31000-32000] (allocated: {}) 3: I1209 03:20:35.176970 16003 hierarchical.cpp:1513] Performed allocation for 1 agents in 195535ns 3: I1209 03:20:35.177286 15999 slave.cpp:1330] Forwarding agent update {"offer_operations":{},"resource_categories":{"oversubscribed":true},"resource_providers":{},"resource_version_uuid":"XmISS2eNQN+\/OSbeYF9flA==","slave_id":{"value":"f8f42914-2de0-41a9-85f8-f86d267dbb86-S0"}} 3: I1209 03:20:35.177892 15999 master.cpp:7227] Received update of agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 at slave(1003)@172.17.0.4:40415 (4dd79e931ff0) with total oversubscribed resources {} 3: I1209 03:20:35.178241 16010 hierarchical.cpp:1513] Performed allocation for 1 agents in 248968ns 3: W1209 03:20:35.178658 15994 process.cpp:2756] Attempted to spawn already running process version@172.17.0.4:40415 3: W1209 03:20:35.179189 15994 process.cpp:2756] Attempted to spawn already running process version@172.17.0.4:40415 3: I1209 03:20:35.179574 16008 hierarchical.cpp:665] Agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 (4dd79e931ff0) updated with total resources cpus:1; mem:512; disk:3701220; ports:[31000-32000] 3: I1209 03:20:35.180243 15994 sched.cpp:232] Version: 1.5.0 3: I1209 03:20:35.180909 16009 sched.cpp:336] New master detected at master@172.17.0.4:40415 3: I1209 03:20:35.181015 16009 sched.cpp:396] Authenticating with master master@172.17.0.4:40415 3: I1209 03:20:35.181035 16009 sched.cpp:403] Using default CRAM-MD5 authenticatee 3: I1209 03:20:35.181309 16006 authenticatee.cpp:121] Creating new client SASL connection 3: I1209 03:20:35.181638 16007 master.cpp:8879] Authenticating scheduler-2f52ff08-b314-49c1-a143-6c8434a301e6@172.17.0.4:40415 3: I1209 03:20:35.181804 15996 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1719)@172.17.0.4:40415 3: I1209 03:20:35.182081 16010 authenticator.cpp:98] Creating new server SASL connection 3: I1209 03:20:35.182386 16004 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1209 03:20:35.182418 16004 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1209 03:20:35.182535 16004 authenticator.cpp:204] Received SASL authentication start 3: I1209 03:20:35.182588 16004 authenticator.cpp:326] Authentication requires more steps 3: I1209 03:20:35.182705 16002 authenticatee.cpp:259] Received SASL authentication step 3: I1209 03:20:35.182847 16008 authenticator.cpp:232] Received SASL authentication step 3: I1209 03:20:35.182884 16008 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4dd79e931ff0' server FQDN: '4dd79e931ff0' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1209 03:20:35.182907 16008 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1209 03:20:35.182946 16008 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1209 03:20:35.182982 16008 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4dd79e931ff0' server FQDN: '4dd79e931ff0' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1209 03:20:35.183003 16008 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1209 03:20:35.183015 16008 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1209 03:20:35.183037 16008 authenticator.cpp:318] Authentication success 3: I1209 03:20:35.183147 15998 authenticatee.cpp:299] Authentication success 3: I1209 03:20:35.183199 16001 master.cpp:8909] Successfully authenticated principal 'test-principal' at scheduler-2f52ff08-b314-49c1-a143-6c8434a301e6@172.17.0.4:40415 3: I1209 03:20:35.183269 15995 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1719)@172.17.0.4:40415 3: I1209 03:20:35.183475 16003 sched.cpp:502] Successfully authenticated with master master@172.17.0.4:40415 3: I1209 03:20:35.183501 16003 sched.cpp:824] Sending SUBSCRIBE call to master@172.17.0.4:40415 3: I1209 03:20:35.183647 16003 sched.cpp:857] Will retry registration in 1.701998445secs if necessary 3: I1209 03:20:35.183882 16006 master.cpp:2962] Received SUBSCRIBE call for framework 'framework1' at scheduler-2f52ff08-b314-49c1-a143-6c8434a301e6@172.17.0.4:40415 3: I1209 03:20:35.183910 16006 master.cpp:2279] Authorizing framework principal 'test-principal' to receive offers for roles '{ role1 }' 3: I1209 03:20:35.184197 16007 hierarchical.cpp:1513] Performed allocation for 1 agents in 205925ns 3: I1209 03:20:35.184501 16010 master.cpp:3042] Subscribing framework framework1 with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ] 3: I1209 03:20:35.184725 16010 master.cpp:9100] Adding framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 (framework1) at scheduler-2f52ff08-b314-49c1-a143-6c8434a301e6@172.17.0.4:40415 with roles { } suppressed 3: I1209 03:20:35.185274 16002 sched.cpp:751] Framework registered with f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 3: I1209 03:20:35.185325 16002 sched.cpp:765] Scheduler::registered took 27675ns 3: I1209 03:20:35.185413 15999 hierarchical.cpp:297] Added framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 3: I1209 03:20:35.187049 15999 hierarchical.cpp:1513] Performed allocation for 1 agents in 1.456524ms 3: I1209 03:20:35.187697 16008 master.cpp:8709] Sending 1 offers to framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 (framework1) at scheduler-2f52ff08-b314-49c1-a143-6c8434a301e6@172.17.0.4:40415 3: I1209 03:20:35.188326 16008 sched.cpp:921] Scheduler::resourceOffers took 128604ns 3: I1209 03:20:35.190662 15996 master.cpp:10629] Removing offer f8f42914-2de0-41a9-85f8-f86d267dbb86-O0 3: I1209 03:20:35.190757 16007 hierarchical.cpp:1513] Performed allocation for 1 agents in 176213ns 3: I1209 03:20:35.190806 15996 master.cpp:4278] Processing ACCEPT call for offers: [ f8f42914-2de0-41a9-85f8-f86d267dbb86-O0 ] on agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 at slave(1003)@172.17.0.4:40415 (4dd79e931ff0) for framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 (framework1) at scheduler-2f52ff08-b314-49c1-a143-6c8434a301e6@172.17.0.4:40415 3: I1209 03:20:35.190953 15996 master.cpp:3656] Authorizing principal 'test-principal' to reserve resources '[{"allocation_info":{"role":"role1"},"name":"mem","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":256.0},"type":"SCALAR"}]' 3: I1209 03:20:35.192452 15996 master.cpp:4611] Applying RESERVE operation for resources [{"allocation_info":{"role":"role1"},"name":"mem","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":256.0},"type":"SCALAR"}] from framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 (framework1) at scheduler-2f52ff08-b314-49c1-a143-6c8434a301e6@172.17.0.4:40415 to agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 at slave(1003)@172.17.0.4:40415 (4dd79e931ff0) 3: I1209 03:20:35.193222 15996 master.cpp:10526] Sending offer operation '' (uuid: ce9f9b92-0aec-48c3-a81f-fcf55e31de2b) to agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 at slave(1003)@172.17.0.4:40415 (4dd79e931ff0) 3: I1209 03:20:35.194658 15999 slave.cpp:3699] Updated checkpointed resources from {} to mem(reservations: [(DYNAMIC,role1,test-principal)]):256 3: I1209 03:20:35.194766 15999 slave.cpp:7385] Updating the state of offer operation '' (uuid: ce9f9b92-0aec-48c3-a81f-fcf55e31de2b) of framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 (latest state: OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED) 3: I1209 03:20:35.195078 16008 master.cpp:10313] Updating the state of offer operation '' (uuid: ce9f9b92-0aec-48c3-a81f-fcf55e31de2b) of framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 (latest state: OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED) 3: I1209 03:20:35.196920 16001 hierarchical.cpp:921] Updated allocation of framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 on agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 from cpus(allocated: role1):1; mem(allocated: role1):512; disk(allocated: role1):3701220; ports(allocated: role1):[31000-32000] to cpus(allocated: role1):1; mem(allocated: role1):256; disk(allocated: role1):3701220; ports(allocated: role1):[31000-32000]; mem(allocated: role1)(reservations: [(DYNAMIC,role1,test-principal)]):256 3: I1209 03:20:35.198595 16001 hierarchical.cpp:1188] Recovered cpus(allocated: role1):1; mem(allocated: role1):256; disk(allocated: role1):3701220; ports(allocated: role1):[31000-32000]; mem(allocated: role1)(reservations: [(DYNAMIC,role1,test-principal)]):256 (total: cpus:1; mem:256; disk:3701220; ports:[31000-32000]; mem(reservations: [(DYNAMIC,role1,test-principal)]):256, allocated: {}) on agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 from framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 3: I1209 03:20:35.200636 16001 hierarchical.cpp:1513] Performed allocation for 1 agents in 1.776921ms 3: I1209 03:20:35.201447 15997 master.cpp:8709] Sending 1 offers to framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 (framework1) at scheduler-2f52ff08-b314-49c1-a143-6c8434a301e6@172.17.0.4:40415 3: I1209 03:20:35.202165 16005 sched.cpp:921] Scheduler::resourceOffers took 158700ns 3: I1209 03:20:35.203219 15995 master.cpp:5488] Processing DECLINE call for offers: [ f8f42914-2de0-41a9-85f8-f86d267dbb86-O1 ] for framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 (framework1) at scheduler-2f52ff08-b314-49c1-a143-6c8434a301e6@172.17.0.4:40415 3: I1209 03:20:35.203510 15994 sched.cpp:232] Version: 1.5.0 3: I1209 03:20:35.204167 16008 sched.cpp:336] New master detected at master@172.17.0.4:40415 3: I1209 03:20:35.204272 16008 sched.cpp:396] Authenticating with master master@172.17.0.4:40415 3: I1209 03:20:35.204294 16008 sched.cpp:403] Using default CRAM-MD5 authenticatee 3: I1209 03:20:35.204346 15995 master.cpp:10629] Removing offer f8f42914-2de0-41a9-85f8-f86d267dbb86-O1 3: I1209 03:20:35.204581 15995 authenticatee.cpp:121] Creating new client SASL connection 3: I1209 03:20:35.204973 16000 master.cpp:8879] Authenticating scheduler-a3ba86d5-006c-40e4-a0b3-e6157fe7a885@172.17.0.4:40415 3: I1209 03:20:35.205152 16005 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1720)@172.17.0.4:40415 3: I1209 03:20:35.205420 16009 authenticator.cpp:98] Creating new server SASL connection 3: I1209 03:20:35.205590 15998 hierarchical.cpp:1188] Recovered cpus(allocated: role1):1; mem(allocated: role1):256; disk(allocated: role1):3701220; ports(allocated: role1):[31000-32000]; mem(allocated: role1)(reservations: [(DYNAMIC,role1,test-principal)]):256 (total: cpus:1; mem:256; disk:3701220; ports:[31000-32000]; mem(reservations: [(DYNAMIC,role1,test-principal)]):256, allocated: {}) on agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 from framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 3: W1209 03:20:35.205680 15998 hierarchical.cpp:1209] Using 365 days to create the refused resources offer filter because the input value is too big 3: I1209 03:20:35.205696 15998 hierarchical.cpp:1234] Framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 filtered agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 for 365days 3: I1209 03:20:35.205698 16003 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1209 03:20:35.205744 16003 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1209 03:20:35.205893 16006 authenticator.cpp:204] Received SASL authentication start 3: I1209 03:20:35.205981 16006 authenticator.cpp:326] Authentication requires more steps 3: I1209 03:20:35.206115 16006 authenticatee.cpp:259] Received SASL authentication step 3: I1209 03:20:35.206329 16004 authenticator.cpp:232] Received SASL authentication step 3: I1209 03:20:35.206372 16004 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4dd79e931ff0' server FQDN: '4dd79e931ff0' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1209 03:20:35.206384 16004 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1209 03:20:35.206415 16004 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1209 03:20:35.206439 16004 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4dd79e931ff0' server FQDN: '4dd79e931ff0' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1209 03:20:35.206455 16004 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1209 03:20:35.206466 16004 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1209 03:20:35.206490 16004 authenticator.cpp:318] Authentication success 3: I1209 03:20:35.206603 15996 authenticatee.cpp:299] Authentication success 3: I1209 03:20:35.206714 15999 master.cpp:8909] Successfully authenticated principal 'test-principal' at scheduler-a3ba86d5-006c-40e4-a0b3-e6157fe7a885@172.17.0.4:40415 3: I1209 03:20:35.206749 16002 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1720)@172.17.0.4:40415 3: I1209 03:20:35.206992 16004 sched.cpp:502] Successfully authenticated with master master@172.17.0.4:40415 3: I1209 03:20:35.207020 16004 sched.cpp:824] Sending SUBSCRIBE call to master@172.17.0.4:40415 3: I1209 03:20:35.207044 16010 hierarchical.cpp:2216] Filtered offer with cpus:1; mem:256; disk:3701220; ports:[31000-32000]; mem(reservations: [(DYNAMIC,role1,test-principal)]):256 on agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 for role role1 of framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 3: I1209 03:20:35.207144 16004 sched.cpp:857] Will retry registration in 24.561819ms if necessary 3: I1209 03:20:35.207173 16010 hierarchical.cpp:1513] Performed allocation for 1 agents in 726808ns 3: I1209 03:20:35.207360 16005 master.cpp:2962] Received SUBSCRIBE call for framework 'framework2' at scheduler-a3ba86d5-006c-40e4-a0b3-e6157fe7a885@172.17.0.4:40415 3: I1209 03:20:35.207386 16005 master.cpp:2279] Authorizing framework principal 'test-principal' to receive offers for roles '{ role2 }' 3: I1209 03:20:35.207891 16003 master.cpp:3042] Subscribing framework framework2 with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ] 3: I1209 03:20:35.208062 16003 master.cpp:9100] Adding framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0001 (framework2) at scheduler-a3ba86d5-006c-40e4-a0b3-e6157fe7a885@172.17.0.4:40415 with roles { } suppressed 3: I1209 03:20:35.208467 16007 sched.cpp:751] Framework registered with f8f42914-2de0-41a9-85f8-f86d267dbb86-0001 3: I1209 03:20:35.208515 16007 sched.cpp:765] Scheduler::registered took 25305ns 3: I1209 03:20:35.208853 15998 hierarchical.cpp:297] Added framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0001 3: I1209 03:20:35.210727 15998 hierarchical.cpp:2216] Filtered offer with mem(reservations: [(DYNAMIC,role1,test-principal)]):256 on agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 for role role1 of framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 3: I1209 03:20:35.210922 15998 hierarchical.cpp:1513] Performed allocation for 1 agents in 1.917021ms 3: I1209 03:20:35.211477 16001 master.cpp:8709] Sending 1 offers to framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0001 (framework2) at scheduler-a3ba86d5-006c-40e4-a0b3-e6157fe7a885@172.17.0.4:40415 3: I1209 03:20:35.212015 16001 sched.cpp:921] Scheduler::resourceOffers took 127654ns 3: I1209 03:20:35.213467 16000 hierarchical.cpp:2216] Filtered offer with mem(reservations: [(DYNAMIC,role1,test-principal)]):256 on agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 for role role1 of framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 3: I1209 03:20:35.213866 16000 hierarchical.cpp:1513] Performed allocation for 1 agents in 1.012973ms 3: I1209 03:20:35.214315 16004 master.cpp:10629] Removing offer f8f42914-2de0-41a9-85f8-f86d267dbb86-O2 3: I1209 03:20:35.214443 16004 master.cpp:4278] Processing ACCEPT call for offers: [ f8f42914-2de0-41a9-85f8-f86d267dbb86-O2 ] on agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 at slave(1003)@172.17.0.4:40415 (4dd79e931ff0) for framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0001 (framework2) at scheduler-a3ba86d5-006c-40e4-a0b3-e6157fe7a885@172.17.0.4:40415 3: I1209 03:20:35.214596 16004 master.cpp:3724] Authorizing principal 'test-principal' to unreserve resources '[{"allocation_info":{"role":"role2"},"name":"mem","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":256.0},"type":"SCALAR"}]' 3: W1209 03:20:35.215744 15998 master.cpp:2364] Dropping UNRESERVE offer operation from framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0001 (framework2) at scheduler-a3ba86d5-006c-40e4-a0b3-e6157fe7a885@172.17.0.4:40415: cpus(allocated: role2):1; mem(allocated: role2):256; disk(allocated: role2):3701220; ports(allocated: role2):[31000-32000] does not contain mem(allocated: role2)(reservations: [(DYNAMIC,role1,test-principal)]):256 3: I1209 03:20:35.217165 16001 hierarchical.cpp:1188] Recovered cpus(allocated: role2):1; mem(allocated: role2):256; disk(allocated: role2):3701220; ports(allocated: role2):[31000-32000] (total: cpus:1; mem:256; disk:3701220; ports:[31000-32000]; mem(reservations: [(DYNAMIC,role1,test-principal)]):256, allocated: {}) on agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 from framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0001 3: I1209 03:20:35.221132 15995 hierarchical.cpp:2216] Filtered offer with mem(reservations: [(DYNAMIC,role1,test-principal)]):256 on agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 for role role1 of framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 3: I1209 03:20:35.221326 15995 hierarchical.cpp:1513] Performed allocation for 1 agents in 1.67662ms 3: I1209 03:20:35.221860 15999 master.cpp:8709] Sending 1 offers to framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0001 (framework2) at scheduler-a3ba86d5-006c-40e4-a0b3-e6157fe7a885@172.17.0.4:40415 3: I1209 03:20:35.222417 15999 sched.cpp:921] Scheduler::resourceOffers took 126169ns 3: I1209 03:20:35.223011 15994 sched.cpp:2009] Asked to stop the driver 3: W1209 03:20:35.223101 16000 sched.cpp:1402] Attempting to decline an unknown offer f8f42914-2de0-41a9-85f8-f86d267dbb86-O2 3: I1209 03:20:35.223264 16000 sched.cpp:1191] Stopping framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0001 3: I1209 03:20:35.223417 16003 master.cpp:5488] Processing DECLINE call for offers: [ f8f42914-2de0-41a9-85f8-f86d267dbb86-O2 ] for framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0001 (framework2) at scheduler-a3ba86d5-006c-40e4-a0b3-e6157fe7a885@172.17.0.4:40415 3: W1209 03:20:35.223459 16003 master.cpp:5509] Ignoring decline of offer f8f42914-2de0-41a9-85f8-f86d267dbb86-O2 since it is no longer valid 3: I1209 03:20:35.223636 16003 master.cpp:9389] Processing TEARDOWN call for framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0001 (framework2) at scheduler-a3ba86d5-006c-40e4-a0b3-e6157fe7a885@172.17.0.4:40415 3: I1209 03:20:35.223667 16003 master.cpp:9401] Removing framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0001 (framework2) at scheduler-a3ba86d5-006c-40e4-a0b3-e6157fe7a885@172.17.0.4:40415 3: I1209 03:20:35.223682 16003 master.cpp:3332] Deactivating framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0001 (framework2) at scheduler-a3ba86d5-006c-40e4-a0b3-e6157fe7a885@172.17.0.4:40415 3: I1209 03:20:35.223866 16008 hierarchical.cpp:405] Deactivated framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0001 3: I1209 03:20:35.224339 16003 master.cpp:10629] Removing offer f8f42914-2de0-41a9-85f8-f86d267dbb86-O3 3: I1209 03:20:35.224586 15995 slave.cpp:3375] Asked to shut down framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0001 by master@172.17.0.4:40415 3: I1209 03:20:35.224624 15995 slave.cpp:3390] Cannot shut down unknown framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0001 3: I1209 03:20:35.224812 16003 master.cpp:5593] Processing REVIVE call for framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 (framework1) at scheduler-2f52ff08-b314-49c1-a143-6c8434a301e6@172.17.0.4:40415 3: I1209 03:20:35.225227 15998 hierarchical.cpp:1188] Recovered cpus(allocated: role2):1; mem(allocated: role2):256; disk(allocated: role2):3701220; ports(allocated: role2):[31000-32000] (total: cpus:1; mem:256; disk:3701220; ports:[31000-32000]; mem(reservations: [(DYNAMIC,role1,test-principal)]):256, allocated: {}) on agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 from framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0001 3: I1209 03:20:35.225569 15998 hierarchical.cpp:344] Removed framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0001 3: I1209 03:20:35.225662 15998 hierarchical.cpp:1335] Revived offers for roles { role1 } of framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 3: I1209 03:20:35.227617 15998 hierarchical.cpp:1513] Performed allocation for 1 agents in 1.807241ms 3: I1209 03:20:35.228000 15998 hierarchical.cpp:1513] Performed allocation for 1 agents in 174305ns 3: I1209 03:20:35.228286 15997 master.cpp:8709] Sending 1 offers to framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 (framework1) at scheduler-2f52ff08-b314-49c1-a143-6c8434a301e6@172.17.0.4:40415 3: I1209 03:20:35.228848 15999 sched.cpp:921] Scheduler::resourceOffers took 136496ns 3: I1209 03:20:35.229473 15994 sched.cpp:2009] Asked to stop the driver 3: I1209 03:20:35.229604 16000 sched.cpp:1191] Stopping framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 3: I1209 03:20:35.229897 16006 master.cpp:9389] Processing TEARDOWN call for framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 (framework1) at scheduler-2f52ff08-b314-49c1-a143-6c8434a301e6@172.17.0.4:40415 3: I1209 03:20:35.229931 16006 master.cpp:9401] Removing framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 (framework1) at scheduler-2f52ff08-b314-49c1-a143-6c8434a301e6@172.17.0.4:40415 3: I1209 03:20:35.229945 16006 master.cpp:3332] Deactivating framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 (framework1) at scheduler-2f52ff08-b314-49c1-a143-6c8434a301e6@172.17.0.4:40415 3: I1209 03:20:35.230083 15996 hierarchical.cpp:405] Deactivated framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 3: I1209 03:20:35.230803 16006 master.cpp:10629] Removing offer f8f42914-2de0-41a9-85f8-f86d267dbb86-O4 3: I1209 03:20:35.230860 15994 slave.cpp:909] Agent terminating 3: I1209 03:20:35.231366 16006 master.cpp:1310] Agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 at slave(1003)@172.17.0.4:40415 (4dd79e931ff0) disconnected 3: I1209 03:20:35.231398 16006 master.cpp:3369] Disconnecting agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 at slave(1003)@172.17.0.4:40415 (4dd79e931ff0) 3: I1209 03:20:35.231468 16006 master.cpp:3388] Deactivating agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 at slave(1003)@172.17.0.4:40415 (4dd79e931ff0) 3: I1209 03:20:35.231979 16003 hierarchical.cpp:1188] Recovered cpus(allocated: role1):1; mem(allocated: role1):256; disk(allocated: role1):3701220; ports(allocated: role1):[31000-32000]; mem(allocated: role1)(reservations: [(DYNAMIC,role1,test-principal)]):256 (total: cpus:1; mem:256; disk:3701220; ports:[31000-32000]; mem(reservations: [(DYNAMIC,role1,test-principal)]):256, allocated: {}) on agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 from framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 3: I1209 03:20:35.232301 16003 hierarchical.cpp:344] Removed framework f8f42914-2de0-41a9-85f8-f86d267dbb86-0000 3: I1209 03:20:35.232380 16003 hierarchical.cpp:762] Agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 deactivated 3: I1209 03:20:35.233683 15997 hierarchical.cpp:1513] Performed allocation for 1 agents in 173475ns 3: I1209 03:20:35.239500 15998 hierarchical.cpp:1513] Performed allocation for 1 agents in 183226ns 3: I1209 03:20:35.239796 15994 master.cpp:1152] Master terminating 3: I1209 03:20:35.240782 16000 hierarchical.cpp:605] Removed agent f8f42914-2de0-41a9-85f8-f86d267dbb86-S0 3: [ OK ] ResourceProviderCapability/ReservationTest.PreventUnreservingAlienResources/1 (110 ms) 3: [----------] 36 tests from ResourceProviderCapability/ReservationTest (3487 ms total) 3: 3: [----------] Global test environment tear-down 3: [==========] 1808 tests from 184 test cases ran. (400693 ms total) 3: [ PASSED ] 1807 tests. 3: [ FAILED ] 1 test, listed below: 3: [ FAILED ] ProvisionerDockerLocalStoreTest.MissingLayer 3: 3: 1 FAILED TEST 3: YOU HAVE 21 DISABLED TESTS 3: 3: I1209 03:20:35.309767 16011 process.cpp:887] Failed to accept socket: future discarded 3/3 Test #3: MesosTests .......................***Failed 405.51 sec 67% tests passed, 1 tests failed out of 3 Total Test time (real) = 422.46 sec The following tests FAILED: 3 - MesosTests (Failed) Errors while running CTest make[3]: Leaving directory `/mesos/build' make[3]: *** [CMakeFiles/check] Error 8 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-1512787386-23335 Untagged: mesos-1512787386-23335:latest Deleted: sha256:00b7a0bd742149b53012a67e246c782a5bb01dc6662a65f75ea4d00021368e17 Deleted: sha256:9bd30a4ee319ee8eab37eeaf40976620b4014172458f7af2cbe17b5e3497222c Deleted: sha256:819b7d45e4198f960df2c3d2f4ad88023ecbaa1f4a727a8c768540abc49dd44f Deleted: sha256:f1813b836b67906322085a2daed4260ab8f6c9cc1cf076988da41b2620b20cfa Deleted: sha256:473c8031f76168397bd643c1190ff0649aae9b79a2aba1181a395c76ca9387ea Deleted: sha256:ee3073ff55cd86a6d2821cea0377d69cc92319ca8227e2e593798d1f5110d875 Deleted: sha256:78e4d597cee3af883e414c873401c9debea075f8e49d00d5e56b4af1a2868abe Deleted: sha256:8a9bf6230393723a554d49c61c5da6c8b80284705acdfb95ea38b32b3e28041d Deleted: sha256:e8a79bcbadad32ecaad55bbafbc921619b6da9833d38868288e61c80b152345e Deleted: sha256:a39be6d00650cc8e9b79a9ccf8b051d351c058f7ed15d698666b0ef9e4ef0113 Deleted: sha256:d348f65be4a4def82f64c13758628d5ec44d1b470655b13cf8a1424fe7486346 Deleted: sha256:0aa7a1e1d0f7b9787e73a1d3eade714a10b5e4ddf4948ac51893262398c0ac78 Deleted: sha256:68d52c2dd1608e80bbb0c680a6afc51ebf17adfb7cde060157127c727b504d73 Deleted: sha256:b4ff211963e9e179d9ab1eb4985c135f73bd258211e68b639449e45f25eee7f3 Deleted: sha256:43ff149fff9f8b10dc904c9d83a983adfdd66f15719d5d8d2bb8f312572b6bba Deleted: sha256:a8d603bac25cbb35791f90c59fb72422bdf9c995813565147e379fa9054aacf9 Deleted: sha256:7070667c8a2642c49abf0cd39f0fce5c5d5e2038720d3279a1973d766a756b4f Deleted: sha256:af791549b99528579abfdc6ca562a985d7c02c35ff019ec73fbdedcfec2a5e6e Deleted: sha256:41ed3446d3a1ed075605dc5442d39168384c529afd06883d032f20e6a0a4a3ce Deleted: sha256:51f8e2876c6a16247880a453ff2bb6c101d59d9c785864cbf0c960c28f9a6a0a Deleted: sha256:08295a55e0434bc34e0dc857cf0187741a78d44d3ed8b9fbe4b03a28302995fa Deleted: sha256:7476f34333e2bccdb81ff394bc6a56c5f4b8d91fc8b10668c4a5bc2127a82312 Deleted: sha256:844af0edfcf730acd4b181c601c3b9e6220ff98db89580a3ee93b354155e7da8 Deleted: sha256:750029f288cc699c7f3dfc38db5d6b074cfdb7ecf598b3fa88c23dab52c40e71 Deleted: sha256:e000394c38124ba14fe8bfbbc8210fe263795a81404ec58bf9262baa6704201d Deleted: sha256:75d9b28eea3d00f56480b904d452ccd3e49072a58869b6c8acb5096782ca8dad Deleted: sha256:091abb2dfaed060100e0ad6bb9353bc94c237aaa80f8e5badd84717aca396258 Deleted: sha256:06658a6cb4d7d53618fe37752c07193c80552c4f18da6d398601b7e61beff435 Deleted: sha256:52d9b3168e55d7eeb5eced869c5e06f54a1a5a6d0a95c8af19b07a239be643e4 Deleted: sha256:5d91717b6eef212b05fa50795714fe0fc596cfb10cff81685f1dd66a9eb7bafe Build step 'Execute shell' marked build as failure