See <https://builds.apache.org/job/Mesos/BUILDTOOL=autotools,COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2)/2933/changes>
Changes: [alexr] Added a comment about deprecation cycle of quota get authz. ------------------------------------------ [...truncated 169340 lines...] I1116 14:23:35.657032 29382 slave.cpp:947] Detecting new master I1116 14:23:35.657058 29386 authenticatee.cpp:121] Creating new client SASL connection I1116 14:23:35.657325 29384 master.cpp:6751] Authenticating (224)@172.17.0.2:59784 I1116 14:23:35.657428 29378 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(947)@172.17.0.2:59784 I1116 14:23:35.657698 29390 authenticator.cpp:98] Creating new server SASL connection I1116 14:23:35.657901 29389 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I1116 14:23:35.657939 29389 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I1116 14:23:35.658054 29387 authenticator.cpp:204] Received SASL authentication start I1116 14:23:35.658112 29387 authenticator.cpp:326] Authentication requires more steps I1116 14:23:35.658207 29389 authenticatee.cpp:259] Received SASL authentication step I1116 14:23:35.658363 29390 authenticator.cpp:232] Received SASL authentication step I1116 14:23:35.658398 29390 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4a08a3766938' server FQDN: '4a08a3766938' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1116 14:23:35.658416 29390 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I1116 14:23:35.658453 29390 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1116 14:23:35.658479 29390 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4a08a3766938' server FQDN: '4a08a3766938' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1116 14:23:35.658493 29390 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1116 14:23:35.658502 29390 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1116 14:23:35.658519 29390 authenticator.cpp:318] Authentication success I1116 14:23:35.658601 29379 authenticatee.cpp:299] Authentication success I1116 14:23:35.658684 29376 master.cpp:6781] Successfully authenticated principal 'test-principal' at (224)@172.17.0.2:59784 I1116 14:23:35.658761 29389 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(947)@172.17.0.2:59784 I1116 14:23:35.658936 29377 slave.cpp:1069] Successfully authenticated with master [email protected]:59784 I1116 14:23:35.659175 29377 slave.cpp:1483] Will retry registration in 14.750546ms if necessary I1116 14:23:35.659364 29378 master.cpp:5160] Registering agent at (224)@172.17.0.2:59784 (4a08a3766938) with id 93511405-8ef4-4e9d-820e-9a5bfcf74782-S0 I1116 14:23:35.659739 29391 registrar.cpp:461] Applied 1 operations in 72970ns; attempting to update the registry I1116 14:23:35.660476 29378 log.cpp:577] Attempting to append 337 bytes to the log I1116 14:23:35.660603 29391 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 3 I1116 14:23:35.661325 29391 replica.cpp:537] Replica received write request for position 3 from __req_res__(6033)@172.17.0.2:59784 I1116 14:23:35.661519 29391 leveldb.cpp:341] Persisting action (356 bytes) to leveldb took 166449ns I1116 14:23:35.661546 29391 replica.cpp:708] Persisted action APPEND at position 3 I1116 14:23:35.662353 29391 replica.cpp:691] Replica received learned notice for position 3 from @0.0.0.0:0 I1116 14:23:35.662786 29391 leveldb.cpp:341] Persisting action (358 bytes) to leveldb took 412063ns I1116 14:23:35.662816 29391 replica.cpp:708] Persisted action APPEND at position 3 I1116 14:23:35.664362 29382 registrar.cpp:506] Successfully updated the registry in 4.560128ms I1116 14:23:35.664582 29380 log.cpp:596] Attempting to truncate the log to 3 I1116 14:23:35.664716 29387 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 4 I1116 14:23:35.665333 29386 slave.cpp:4263] Received ping from slave-observer(439)@172.17.0.2:59784 I1116 14:23:35.665436 29381 replica.cpp:537] Replica received write request for position 4 from __req_res__(6034)@172.17.0.2:59784 I1116 14:23:35.665477 29389 master.cpp:5231] Registered agent 93511405-8ef4-4e9d-820e-9a5bfcf74782-S0 at (224)@172.17.0.2:59784 (4a08a3766938) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1116 14:23:35.665630 29386 slave.cpp:1115] Registered with master [email protected]:59784; given agent ID 93511405-8ef4-4e9d-820e-9a5bfcf74782-S0 I1116 14:23:35.665683 29386 fetcher.cpp:86] Clearing fetcher cache I1116 14:23:35.665799 29381 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 335183ns I1116 14:23:35.665827 29381 replica.cpp:708] Persisted action TRUNCATE at position 4 I1116 14:23:35.665837 29387 status_update_manager.cpp:184] Resuming sending status updates I1116 14:23:35.666018 29385 hierarchical.cpp:485] Added agent 93511405-8ef4-4e9d-820e-9a5bfcf74782-S0 (4a08a3766938) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I1116 14:23:35.666086 29386 slave.cpp:1138] Checkpointing SlaveInfo to '/tmp/SlaveTest_ContainersEndpoint_sTOObE/meta/slaves/93511405-8ef4-4e9d-820e-9a5bfcf74782-S0/slave.info' I1116 14:23:35.666407 29376 replica.cpp:691] Replica received learned notice for position 4 from @0.0.0.0:0 I1116 14:23:35.666828 29376 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 403646ns I1116 14:23:35.666908 29376 leveldb.cpp:399] Deleting ~2 keys from leveldb took 62271ns I1116 14:23:35.666930 29376 replica.cpp:708] Persisted action TRUNCATE at position 4 I1116 14:23:35.667445 29385 hierarchical.cpp:1789] No inverse offers to send out! I1116 14:23:35.667518 29385 hierarchical.cpp:1309] Performed allocation for agent 93511405-8ef4-4e9d-820e-9a5bfcf74782-S0 in 1.442619ms I1116 14:23:35.667959 29380 master.cpp:6580] Sending 1 offers to framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 (default) at [email protected]:59784 I1116 14:23:35.668453 29390 sched.cpp:917] Scheduler::resourceOffers took 141515ns I1116 14:23:35.670073 29389 master.cpp:3587] Processing ACCEPT call for offers: [ 93511405-8ef4-4e9d-820e-9a5bfcf74782-O0 ] on agent 93511405-8ef4-4e9d-820e-9a5bfcf74782-S0 at (224)@172.17.0.2:59784 (4a08a3766938) for framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 (default) at [email protected]:59784 I1116 14:23:35.670142 29389 master.cpp:3174] Authorizing framework principal 'test-principal' to launch task c54424d2-7170-4003-97a5-17a2dae4dd04 W1116 14:23:35.671488 29389 validation.cpp:920] Executor 'default' for task 'c54424d2-7170-4003-97a5-17a2dae4dd04' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W1116 14:23:35.671511 29389 validation.cpp:932] Executor 'default' for task 'c54424d2-7170-4003-97a5-17a2dae4dd04' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I1116 14:23:35.671769 29389 master.cpp:8343] Adding task c54424d2-7170-4003-97a5-17a2dae4dd04 with resources cpus(*):0.1; mem(*):32 on agent 93511405-8ef4-4e9d-820e-9a5bfcf74782-S0 (4a08a3766938) I1116 14:23:35.671874 29389 master.cpp:4239] Launching task c54424d2-7170-4003-97a5-17a2dae4dd04 of framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 (default) at [email protected]:59784 with resources cpus(*):0.1; mem(*):32 on agent 93511405-8ef4-4e9d-820e-9a5bfcf74782-S0 at (224)@172.17.0.2:59784 (4a08a3766938) I1116 14:23:35.672191 29377 slave.cpp:1547] Got assigned task 'c54424d2-7170-4003-97a5-17a2dae4dd04' for framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 I1116 14:23:35.672921 29377 slave.cpp:1709] Launching task 'c54424d2-7170-4003-97a5-17a2dae4dd04' for framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 I../../src/tests/slave_tests.cpp:2143: Failure Failed to wait 15secs for status ../../src/tests/slave_tests.cpp:2138: Failure Actual function call count doesn't match EXPECT_CALL(sched, statusUpdate(&driver, _))... Expected: to be called once Actual: never called - unsatisfied and active 1116 14:23:35.673419 29378 hierarchical.cpp:1018] Recovered cpus(*):1.9; mem(*):992; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):0.1; mem(*):32) on agent 93511405-8ef4-4e9d-820e-9a5bfcf74782-S0 from framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 I1116 14:23:35.673576 29377 paths.cpp:530] Trying to chown '/tmp/SlaveTest_ContainersEndpoint_sTOObE/slaves/93511405-8ef4-4e9d-820e-9a5bfcf74782-S0/frameworks/93511405-8ef4-4e9d-820e-9a5bfcf74782-0000/executors/default/runs/7eec4525-a4d2-4f74-b1b8-d512fcb5fcda' to user 'mesos' I1116 14:23:50.670724 29376 master.cpp:1297] Framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 (default) at [email protected]:59784 disconnected I1116 14:24:17.259991 29378 hierarchical.cpp:1055] Framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 filtered agent 93511405-8ef4-4e9d-820e-9a5bfcf74782-S0 for 5secs I1116 14:24:17.259981 29376 master.cpp:2919] Disconnecting framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 (default) at [email protected]:59784 I1116 14:24:17.260100 29376 master.cpp:2943] Deactivating framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 (default) at [email protected]:59784 I1116 14:24:17.260221 29376 master.cpp:1310] Giving framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 (default) at [email protected]:59784 0ns to failover I1116 14:24:17.263911 29376 master.cpp:6432] Framework failover timeout, removing framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 (default) at [email protected]:59784 I1116 14:24:17.263993 29376 master.cpp:7176] Removing framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 (default) at [email protected]:59784 I1116 14:24:17.264276 29376 master.cpp:7721] Updating the state of task c54424d2-7170-4003-97a5-17a2dae4dd04 of framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I1116 14:24:17.264622 29378 hierarchical.cpp:1880] Filtered offer with cpus(*):1.9; mem(*):992; disk(*):1024; ports(*):[31000-32000] on agent 93511405-8ef4-4e9d-820e-9a5bfcf74782-S0 for framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 I1116 14:24:17.264714 29378 hierarchical.cpp:1694] No allocations performed I1116 14:24:17.264807 29378 hierarchical.cpp:1789] No inverse offers to send out! I1116 14:24:17.264891 29378 hierarchical.cpp:1286] Performed allocation for 1 agents in 1.30158ms I1116 14:24:17.265056 29376 master.cpp:7817] Removing task c54424d2-7170-4003-97a5-17a2dae4dd04 with resources cpus(*):0.1; mem(*):32 of framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 on agent 93511405-8ef4-4e9d-820e-9a5bfcf74782-S0 at (224)@172.17.0.2:59784 (4a08a3766938) I1116 14:24:17.265498 29376 master.cpp:7846] Removing executor 'default' with resources {} of framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 on agent 93511405-8ef4-4e9d-820e-9a5bfcf74782-S0 at (224)@172.17.0.2:59784 (4a08a3766938) I1116 14:24:17.265722 29378 hierarchical.cpp:386] Deactivated framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 I1116 14:24:17.266482 29378 hierarchical.cpp:1018] Recovered cpus(*):0.1; mem(*):32 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 93511405-8ef4-4e9d-820e-9a5bfcf74782-S0 from framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 I1116 14:24:17.266857 29378 hierarchical.cpp:337] Removed framework 93511405-8ef4-4e9d-820e-9a5bfcf74782-0000 ../../src/tests/slave_tests.cpp:2134: Failure Actual function call count doesn't match EXPECT_CALL(exec, launchTask(_, _))... Expected: to be called once Actual: never called - unsatisfied and active ../../src/tests/slave_tests.cpp:2113: Failure Actual function call count doesn't match EXPECT_CALL(exec, registered(_, _, _, _))... Expected: to be called once Actual: never called - unsatisfied and active I1116 14:24:17.268458 29358 master.cpp:1097] Master terminating *** Error in `/mesos/mesos-1.2.0/_build/src/.libs/lt-mesos-tests': free(): invalid pointer: 0x00002b61d00029d8 *** I1116 14:24:17.269240 29380 hierarchical.cpp:517] Removed agent 93511405-8ef4-4e9d-820e-9a5bfcf74782-S0 *** Aborted at 1479306257 (unix time) try "date -d @1479306257" if you are using GNU date *** PC: @ 0x2b61ba8b7c37 (unknown) *** SIGABRT (@0x3e8000072ae) received by PID 29358 (TID 0x2b61c3b26700) from PID 29358; stack trace: *** @ 0x2b61ba673330 (unknown) @ 0x2b61ba8b7c37 (unknown) @ 0x2b61ba8bb028 (unknown) @ 0x2b61ba8f42a4 (unknown) @ 0x2b61ba90055e (unknown) @ 0x2b61b9efd36f (unknown) [ FAILED ] SlaveTest.ContainersEndpoint (41658 ms) [ RUN ] SlaveTest.TerminalTaskContainerizerUpdateFailsWithLost I1116 14:24:17.280805 29358 cluster.cpp:158] Creating default 'local' authorizer @ 0x2b61b7b79390 mesos::FrameworkID::SharedDtor() I1116 14:24:17.283193 29358 leveldb.cpp:174] Opened db in 2.13417ms I1116 14:24:17.284410 29358 leveldb.cpp:181] Compacted db in 1.204235ms I1116 14:24:17.284482 29358 leveldb.cpp:196] Created db iterator in 35687ns I1116 14:24:17.284507 29358 leveldb.cpp:202] Seeked to beginning of db in 8874ns I1116 14:24:17.284524 29358 leveldb.cpp:271] Iterated through 0 keys in the db in 6860ns I1116 14:24:17.284561 29358 replica.cpp:776] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I1116 14:24:17.285171 29379 recover.cpp:451] Starting replica recovery I1116 14:24:17.285408 29379 recover.cpp:477] Replica is in EMPTY status @ 0x2b61b7b792e6 mesos::FrameworkID::~FrameworkID() I1116 14:24:17.286548 29381 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from __req_res__(6035)@172.17.0.2:59784 I1116 14:24:17.287045 29388 recover.cpp:197] Received a recover response from a replica in EMPTY status I1116 14:24:17.287547 29379 recover.cpp:568] Updating replica status to STARTING I1116 14:24:17.288337 29390 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 604834ns I1116 14:24:17.288368 29390 replica.cpp:320] Persisted replica status to STARTING I1116 14:24:17.288518 29381 master.cpp:380] Master d355d93a-396e-457a-a8f0-269d36d09574 (4a08a3766938) started on 172.17.0.2:59784 I1116 14:24:17.288560 29381 master.cpp:382] 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/bfJ5ir/credentials" --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" --quiet="false" --recovery_agent_removal_limit="100%" --registry="replicated_log" --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="/mesos/mesos-1.2.0/_inst/share/mesos/webui" --work_dir="/tmp/bfJ5ir/master" --zk_session_timeout="10secs" I1116 14:24:17.289006 29381 master.cpp:432] Master only allowing authenticated frameworks to register I1116 14:24:17.289021 29381 master.cpp:446] Master only allowing authenticated agents to register I1116 14:24:17.289032 29381 master.cpp:459] Master only allowing authenticated HTTP frameworks to register I1116 14:24:17.289047 29381 credentials.hpp:37] Loading credentials for authentication from '/tmp/bfJ5ir/credentials' I1116 14:24:17.289327 29381 master.cpp:504] Using default 'crammd5' authenticator I1116 14:24:17.289475 29381 http.cpp:895] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I1116 14:24:17.289664 29381 http.cpp:895] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I1116 14:24:17.289722 29382 recover.cpp:477] Replica is in STARTING status I1116 14:24:17.289841 29381 http.cpp:895] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I1116 14:24:17.289963 29381 master.cpp:584] Authorization enabled I1116 14:24:17.290206 29379 hierarchical.cpp:149] Initialized hierarchical allocator process I1116 14:24:17.290213 29378 whitelist_watcher.cpp:77] No whitelist given I1116 14:24:17.290555 29378 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from __req_res__(6036)@172.17.0.2:59784 I1116 14:24:17.291007 29388 recover.cpp:197] Received a recover response from a replica in STARTING status I1116 14:24:17.291523 29382 recover.cpp:568] Updating replica status to VOTING @ 0x2b61b7436bc3 mesos::internal::slave::Framework::launchExecutor() I1116 14:24:17.292187 29378 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 512336ns I1116 14:24:17.292225 29378 replica.cpp:320] Persisted replica status to VOTING I1116 14:24:17.292408 29389 recover.cpp:582] Successfully joined the Paxos group I1116 14:24:17.292619 29389 recover.cpp:466] Recover process terminated I1116 14:24:17.292975 29382 master.cpp:2033] Elected as the leading master! I1116 14:24:17.293002 29382 master.cpp:1560] Recovering from registrar I1116 14:24:17.293126 29380 registrar.cpp:329] Recovering registrar I1116 14:24:17.293697 29384 log.cpp:553] Attempting to start the writer I1116 14:24:17.294970 29376 replica.cpp:493] Replica received implicit promise request from __req_res__(6037)@172.17.0.2:59784 with proposal 1 I1116 14:24:17.295361 29376 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 369573ns I1116 14:24:17.295387 29376 replica.cpp:342] Persisted promised to 1 I1116 14:24:17.296106 29385 coordinator.cpp:238] Coordinator attempting to fill missing positions I1116 14:24:17.297317 29386 replica.cpp:388] Replica received explicit promise request from __req_res__(6038)@172.17.0.2:59784 for position 0 with proposal 2 I1116 14:24:17.297731 29386 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 387234ns I1116 14:24:17.297760 29386 replica.cpp:708] Persisted action NOP at position 0 I1116 14:24:17.298951 29390 replica.cpp:537] Replica received write request for position 0 from __req_res__(6039)@172.17.0.2:59784 I1116 14:24:17.299027 29390 leveldb.cpp:436] Reading position from leveldb took 53173ns I1116 14:24:17.299481 29390 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 410181ns I1116 14:24:17.299513 29390 replica.cpp:708] Persisted action NOP at position 0 I1116 14:24:17.300179 29379 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0 I1116 14:24:17.300734 29379 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 507833ns I1116 14:24:17.300767 29379 replica.cpp:708] Persisted action NOP at position 0 I1116 14:24:17.301431 29376 log.cpp:569] Writer started with ending position 0 I1116 14:24:17.302471 29385 leveldb.cpp:436] Reading position from leveldb took 67289ns @ 0x2b61b7434d0e mesos::internal::slave::Slave::_run() I1116 14:24:17.303354 29385 registrar.cpp:362] Successfully fetched the registry (0B) in 10.19008ms I1116 14:24:17.303483 29385 registrar.cpp:461] Applied 1 operations in 32290ns; attempting to update the registry I1116 14:24:17.304107 29384 log.cpp:577] Attempting to append 168 bytes to the log I1116 14:24:17.304214 29376 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1 I1116 14:24:17.304811 29390 replica.cpp:537] Replica received write request for position 1 from __req_res__(6040)@172.17.0.2:59784 I1116 14:24:17.305248 29390 leveldb.cpp:341] Persisting action (187 bytes) to leveldb took 409374ns I1116 14:24:17.305274 29390 replica.cpp:708] Persisted action APPEND at position 1 I1116 14:24:17.305850 29381 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0 I1116 14:24:17.306349 29381 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 483404ns I1116 14:24:17.306372 29381 replica.cpp:708] Persisted action APPEND at position 1 I1116 14:24:17.307277 29388 registrar.cpp:506] Successfully updated the registry in 3.725056ms I1116 14:24:17.307409 29388 registrar.cpp:392] Successfully recovered registrar I1116 14:24:17.307507 29385 log.cpp:596] Attempting to truncate the log to 1 I1116 14:24:17.307761 29376 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2 I1116 14:24:17.307950 29384 master.cpp:1676] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I1116 14:24:17.307979 29385 hierarchical.cpp:176] Skipping recovery of hierarchical allocator: nothing to recover @ 0x1491258 mesos::internal::tests::MockSlave::unmocked__run() I1116 14:24:17.308599 29386 replica.cpp:537] Replica received write request for position 2 from __req_res__(6041)@172.17.0.2:59784 I1116 14:24:17.309115 29386 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 476855ns I1116 14:24:17.309139 29386 replica.cpp:708] Persisted action TRUNCATE at position 2 I1116 14:24:17.309839 29380 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0 I1116 14:24:17.310283 29380 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 417953ns I1116 14:24:17.310349 29380 leveldb.cpp:399] Deleting ~1 keys from leveldb took 46391ns I1116 14:24:17.310375 29380 replica.cpp:708] Persisted action TRUNCATE at position 2 @ 0x14a156b _ZN7testing8internal12InvokeHelperIvSt5tupleIJRKN7process6FutureIbEERKN5mesos13FrameworkInfoERKNS8_12ExecutorInfoERK6OptionINS8_8TaskInfoEERKSF_INS8_13TaskGroupInfoEEEEE12InvokeMethodINS8_8internal5tests9MockSlaveEMST_FvS7_SB_SE_SJ_SN_EEEvPT_T0_RKSO_ @ 0x14a1482 _ZNK7testing8internal18InvokeMethodActionIN5mesos8internal5tests9MockSlaveEMS5_FvRKN7process6FutureIbEERKNS2_13FrameworkInfoERKNS2_12ExecutorInfoERK6OptionINS2_8TaskInfoEERKSH_INS2_13TaskGroupInfoEEEE7PerformIvSt5tupleIJSA_SD_SG_SL_SP_EEEET_RKT0_ @ 0x14a142f _ZN7testing17PolymorphicActionINS_8internal18InvokeMethodActionIN5mesos8internal5tests9MockSlaveEMS6_FvRKN7process6FutureIbEERKNS3_13FrameworkInfoERKNS3_12ExecutorInfoERK6OptionINS3_8TaskInfoEERKSI_INS3_13TaskGroupInfoEEEEEE15MonomorphicImplIFvSB_SE_SH_SM_SQ_EE7PerformERKSt5tupleIJSB_SE_SH_SM_SQ_EE @ 0x14b1bf6 _ZNK7testing6ActionIFvRKN7process6FutureIbEERKN5mesos13FrameworkInfoERKNS6_12ExecutorInfoERK6OptionINS6_8TaskInfoEERKSD_INS6_13TaskGroupInfoEEEE7PerformERKSt5tupleIJS5_S9_SC_SH_SL_EE @ 0x14b1b3d testing::internal::ActionResultHolder<>::PerformAction<>() @ 0x14aef5c testing::internal::FunctionMockerBase<>::UntypedPerformAction() @ 0x1e1d8e9 testing::internal::UntypedFunctionMockerBase::UntypedInvokeWith() @ 0x14ab0b8 _ZN7testing8internal18FunctionMockerBaseIFvRKN7process6FutureIbEERKN5mesos13FrameworkInfoERKNS7_12ExecutorInfoERK6OptionINS7_8TaskInfoEERKSE_INS7_13TaskGroupInfoEEEE10InvokeWithERKSt5tupleIJS6_SA_SD_SI_SM_EE @ 0x14ab080 testing::internal::FunctionMocker<>::Invoke() @ 0x1499700 mesos::internal::tests::MockSlave::_run() @ 0x2b61b74c5220 _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS_6FutureIbEERKNS1_13FrameworkInfoERKNS1_12ExecutorInfoERK6OptionINS1_8TaskInfoEERKSF_INS1_13TaskGroupInfoEES6_S9_SC_SH_SL_EEvRKNS_3PIDIT_EEMSP_FvT0_T1_T2_T3_T4_ET5_T6_T7_T8_T9_ENKUlPNS_11ProcessBaseEE_clES16_ @ 0x2b61b74c4ce2 _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchIN5mesos8internal5slave5SlaveERKNS0_6FutureIbEERKNS5_13FrameworkInfoERKNS5_12ExecutorInfoERK6OptionINS5_8TaskInfoEERKSJ_INS5_13TaskGroupInfoEESA_SD_SG_SL_SP_EEvRKNS0_3PIDIT_EEMST_FvT0_T1_T2_T3_T4_ET5_T6_T7_T8_T9_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_ @ 0x2b61b837d328 std::function<>::operator()() @ 0x2b61b83644c4 process::ProcessBase::visit() @ 0x2b61b83c2a3e process::DispatchEvent::visit() @ 0x903ff1 process::ProcessBase::serve() @ 0x2b61b83621b4 process::ProcessManager::resume() @ 0x2b61b836ce1c process::ProcessManager::init_threads()::$_0::operator()() @ 0x2b61b836cd25 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvE3$_0vEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE @ 0x2b61b836ccf5 std::_Bind_simple<>::operator()() @ 0x2b61b836cccc std::thread::_Impl<>::_M_run() make[4]: *** [check-local] Aborted (core dumped) make[4]: Leaving directory `/mesos/mesos-1.2.0/_build/src' make[3]: *** [check-am] Error 2 make[3]: Leaving directory `/mesos/mesos-1.2.0/_build/src' make[2]: *** [check] Error 2 make[2]: Leaving directory `/mesos/mesos-1.2.0/_build/src' make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `/mesos/mesos-1.2.0/_build' make: *** [distcheck] Error 1 + docker rmi mesos-1479303810-8396 Untagged: mesos-1479303810-8396:latest Deleted: sha256:c459a36e7407d47a0cf4e91676ad9f507d61c4d09deab54761cd7e13122d555d Deleted: sha256:a83f8f734384bcea41acb0b0ffa07c627379883e2555164e0b151a2fb9611735 Deleted: sha256:d56e4617eb19204b587a51dd1616d69d1457c8c7097c3103f675c1bda54e2dee Deleted: sha256:90394909e55d6a11b475f12373aa6dd6de7558a15a61a497a5c103ca5951fde9 Deleted: sha256:9b60e6bafd459aeaae099f5f9fb61eac6eb3f88cda5d35c88713276312425a60 Deleted: sha256:8b869f6be2043a84d3672eb4a36f2589b1352895b3c39ce1e5fecd21ad9487b3 Deleted: sha256:c31d2a37c42cd426d60ac6701e1544e0c5c256e008c68a6818b200b545085731 Deleted: sha256:d7104b0385a3a41719e055aeda9f7408ad1b2c26177f7913002b3daee6f0f24e Deleted: sha256:38ddc4a60d1ecdad4fab78cfe9a9310e825719f3bf165639e32b299918d02934 Deleted: sha256:f073645a92b98c77bad29928cfaa2719f162a62a2db05ff9dca06c50aa8026d6 Deleted: sha256:e7402968848d91fb0a82d5315742d190f550170db365d3069f3958bade79b73d Deleted: sha256:8dfaeff8347f8825a1991a8df6edd503318641e2f789d71aae901f0721b8d970 Deleted: sha256:93e0f91ed662f17a9ade092a2c1ff142b3187ec4639ebeebb6a3743d44a2aaad Deleted: sha256:f6fe521b7e6db95d8e9bb32e96c3671ca1868f2fe4233e4dd9b5937f47abea10 Deleted: sha256:d922559fbc14d3cbecb35bc40c94d11628ad32c31079cd51ef985824260a63d9 Deleted: sha256:3b4a607b596ee524c2663b9c35ba1a3527b1948ef42a6eece9116be4f946fbb4 Deleted: sha256:b51a191d8bc887e129599685833ede852b8076e9469abdfac959ae9e72696d39 Deleted: sha256:9d2c10593a6b6b3d5b0df91c34a8358e8300866167ecf9524b9175b49f52370a Deleted: sha256:cfd502d8fc5d8c39d3f7939f4c91729742cd4f88956e2352d3b6d0ef7fb7471e Deleted: sha256:127cc155325ec27f9e1efaeb3f90bec1c093190acb8c3b49977cee43b65e0fe7 Deleted: sha256:7d653bfa1e89fc6799143ae328ff9faf3593f537fa18bc1826de45dbff5febfc Deleted: sha256:8bcd3d2bcd04b5d496eae2e1f815fd8c3940231a317906f35aaeeca396804301 Deleted: sha256:d608953e82482a0a9d950c84c55624de3572e64d15694c46922ab50495d5d9f1 Deleted: sha256:5aea049e3625e43841fff8814bc3127262e719a70a32a7f09652d5504922f794 Build step 'Execute shell' marked build as failure
