See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/2393/changes>
Changes: [yujie.jay] Added three tuples overload for process::await. [yujie.jay] Added PosixDiskIsolator to monitor container disk usage and to enforce [yujie.jay] Added tests for verifying PosixDiskIsolator. ------------------------------------------ [...truncated 73639 lines...] I0116 23:04:34.489198 13248 replica.cpp:511] Replica received write request for position 3 I0116 23:04:34.501888 13247 slave.cpp:1075] Will retry registration in 25.469068ms if necessary I0116 23:04:34.501998 13241 master.cpp:3264] Ignoring register slave message from slave(223)@67.195.81.189:55479 (proserpina.apache.org) as admission is already in progress I0116 23:04:34.521873 13248 leveldb.cpp:343] Persisting action (343 bytes) to leveldb took 32.641047ms I0116 23:04:34.521920 13248 replica.cpp:679] Persisted action at 3 I0116 23:04:34.523221 13252 replica.cpp:658] Replica received learned notice for position 3 I0116 23:04:34.527606 13248 slave.cpp:1075] Will retry registration in 56.114792ms if necessary I0116 23:04:34.527806 13243 master.cpp:3264] Ignoring register slave message from slave(223)@67.195.81.189:55479 (proserpina.apache.org) as admission is already in progress I0116 23:04:34.545444 13252 leveldb.cpp:343] Persisting action (345 bytes) to leveldb took 22.186585ms I0116 23:04:34.545487 13252 replica.cpp:679] Persisted action at 3 I0116 23:04:34.545512 13252 replica.cpp:664] Replica learned APPEND action at position 3 I0116 23:04:34.547128 13249 registrar.cpp:490] Successfully updated the 'registry' in 61.360128ms I0116 23:04:34.547479 13244 log.cpp:703] Attempting to truncate the log to 3 I0116 23:04:34.547657 13255 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 4 I0116 23:04:34.547994 13243 slave.cpp:2588] Received ping from slave-observer(196)@67.195.81.189:55479 I0116 23:04:34.547956 13242 master.cpp:3330] Registered slave 20150116-230434-3176252227-55479-13226-S0 at slave(223)@67.195.81.189:55479 (proserpina.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0116 23:04:34.548297 13246 hierarchical_allocator_process.hpp:453] Added slave 20150116-230434-3176252227-55479-13226-S0 (proserpina.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available) I0116 23:04:34.548454 13243 slave.cpp:781] Registered with master [email protected]:55479; given slave ID 20150116-230434-3176252227-55479-13226-S0 I0116 23:04:34.548467 13246 hierarchical_allocator_process.hpp:839] No resources available to allocate! I0116 23:04:34.548526 13246 hierarchical_allocator_process.hpp:764] Performed allocation for slave 20150116-230434-3176252227-55479-13226-S0 in 170840ns I0116 23:04:34.548588 13249 replica.cpp:511] Replica received write request for position 4 I0116 23:04:34.548602 13245 status_update_manager.cpp:178] Resuming sending status updates I0116 23:04:34.556494 13226 sched.cpp:151] Version: 0.22.0 I0116 23:04:34.557027 13240 sched.cpp:248] New master detected at [email protected]:55479 I0116 23:04:34.557142 13240 sched.cpp:304] Authenticating with master [email protected]:55479 I0116 23:04:34.557160 13240 sched.cpp:311] Using default CRAM-MD5 authenticatee I0116 23:04:34.557337 13254 authenticatee.hpp:138] Creating new client SASL connection I0116 23:04:34.557535 13243 master.cpp:4130] Authenticating [email protected]:55479 I0116 23:04:34.557574 13243 master.cpp:4141] Using default CRAM-MD5 authenticator I0116 23:04:34.562115 13249 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 13.479774ms I0116 23:04:34.562158 13249 replica.cpp:679] Persisted action at 4 I0116 23:04:34.569121 13249 authenticator.hpp:170] Creating new server SASL connection I0116 23:04:34.569238 13245 replica.cpp:658] Replica received learned notice for position 4 I0116 23:04:34.569551 13243 authenticatee.hpp:229] Received SASL authentication mechanisms: CRAM-MD5 I0116 23:04:34.569593 13243 authenticatee.hpp:255] Attempting to authenticate with mechanism 'CRAM-MD5' I0116 23:04:34.569710 13250 authenticator.hpp:276] Received SASL authentication start I0116 23:04:34.569769 13250 authenticator.hpp:398] Authentication requires more steps I0116 23:04:34.569861 13250 authenticatee.hpp:275] Received SASL authentication step I0116 23:04:34.570025 13251 authenticator.hpp:304] Received SASL authentication step I0116 23:04:34.570086 13251 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0116 23:04:34.570103 13251 auxprop.cpp:171] Looking up auxiliary property '*userPassword' I0116 23:04:34.570135 13251 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0116 23:04:34.570160 13251 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0116 23:04:34.570176 13251 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0116 23:04:34.570184 13251 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0116 23:04:34.570202 13251 authenticator.hpp:390] Authentication success I0116 23:04:34.570305 13251 authenticatee.hpp:315] Authentication success I0116 23:04:34.570358 13252 master.cpp:4188] Successfully authenticated principal 'test-principal' at [email protected]:55479 I0116 23:04:34.570817 13253 sched.cpp:392] Successfully authenticated with master [email protected]:55479 I0116 23:04:34.570848 13253 sched.cpp:515] Sending registration request to [email protected]:55479 I0116 23:04:34.570916 13253 sched.cpp:548] Will retry registration in 290.546477ms if necessary I0116 23:04:34.571070 13254 master.cpp:1417] Received registration request for framework 'default' at [email protected]:55479 I0116 23:04:34.571130 13254 master.cpp:1298] Authorizing framework principal 'test-principal' to receive offers for role '*' I0116 23:04:34.571719 13248 master.cpp:1481] Registering framework 20150116-230434-3176252227-55479-13226-0000 (default) at [email protected]:55479 I0116 23:04:34.571979 13249 hierarchical_allocator_process.hpp:319] Added framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.572489 13250 sched.cpp:442] Framework registered with 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.572535 13249 hierarchical_allocator_process.hpp:746] Performed allocation for 1 slaves in 378377ns I0116 23:04:34.572639 13250 sched.cpp:456] Scheduler::registered took 95419ns I0116 23:04:34.572865 13244 master.cpp:4072] Sending 1 offers to framework 20150116-230434-3176252227-55479-13226-0000 (default) at [email protected]:55479 I0116 23:04:34.576038 13242 sched.cpp:605] Scheduler::resourceOffers took 577982ns I0116 23:04:34.576820 13242 master.cpp:2541] Processing reply for offers: [ 20150116-230434-3176252227-55479-13226-O0 ] on slave 20150116-230434-3176252227-55479-13226-S0 at slave(223)@67.195.81.189:55479 (proserpina.apache.org) for framework 20150116-230434-3176252227-55479-13226-0000 (default) at [email protected]:55479 I0116 23:04:34.576858 13242 master.cpp:2647] Authorizing framework principal 'test-principal' to launch task 0 as user 'jenkins' W0116 23:04:34.577663 13250 master.cpp:2124] Executor default for task 0 uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W0116 23:04:34.577697 13250 master.cpp:2136] Executor default for task 0 uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I0116 23:04:34.577960 13250 master.hpp:766] Adding task 0 with resources cpus(*):2; mem(*):1024 on slave 20150116-230434-3176252227-55479-13226-S0 (proserpina.apache.org) I0116 23:04:34.578147 13250 master.cpp:2897] Launching task 0 of framework 20150116-230434-3176252227-55479-13226-0000 (default) at [email protected]:55479 with resources cpus(*):2; mem(*):1024 on slave 20150116-230434-3176252227-55479-13226-S0 at slave(223)@67.195.81.189:55479 (proserpina.apache.org) I0116 23:04:34.578449 13244 slave.cpp:1130] Got assigned task 0 for framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.578651 13253 hierarchical_allocator_process.hpp:610] Updated allocation of framework 20150116-230434-3176252227-55479-13226-0000 on slave 20150116-230434-3176252227-55479-13226-S0 from cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] to cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0116 23:04:34.578914 13253 hierarchical_allocator_process.hpp:653] Recovered disk(*):1024; ports(*):[31000-32000] (total allocatable: disk(*):1024; ports(*):[31000-32000]) on slave 20150116-230434-3176252227-55479-13226-S0 from framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.578956 13253 hierarchical_allocator_process.hpp:689] Framework 20150116-230434-3176252227-55479-13226-0000 filtered slave 20150116-230434-3176252227-55479-13226-S0 for 5secs I0116 23:04:34.579202 13244 slave.cpp:1245] Launching task 0 for framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.581748 13244 slave.cpp:3921] Launching executor default of framework 20150116-230434-3176252227-55479-13226-0000 in work directory '/tmp/GarbageCollectorIntegrationTest_DiskUsage_t2Qxkd/slaves/20150116-230434-3176252227-55479-13226-S0/frameworks/20150116-230434-3176252227-55479-13226-0000/executors/default/runs/3594e4eb-14a1-4217-af32-6a0a57fc4329' I0116 23:04:34.584486 13244 exec.cpp:147] Version: 0.22.0 I0116 23:04:34.584779 13248 exec.cpp:197] Executor started at: executor(79)@67.195.81.189:55479 with pid 13226 I0116 23:04:34.585008 13244 slave.cpp:1368] Queuing task '0' for executor default of framework '20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.585186 13244 slave.cpp:566] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_DiskUsage_t2Qxkd/slaves/20150116-230434-3176252227-55479-13226-S0/frameworks/20150116-230434-3176252227-55479-13226-0000/executors/default/runs/3594e4eb-14a1-4217-af32-6a0a57fc4329' I0116 23:04:34.585315 13244 slave.cpp:2890] Monitoring executor 'default' of framework '20150116-230434-3176252227-55479-13226-0000' in container '3594e4eb-14a1-4217-af32-6a0a57fc4329' I0116 23:04:34.585480 13244 slave.cpp:1912] Got registration for executor 'default' of framework 20150116-230434-3176252227-55479-13226-0000 from executor(79)@67.195.81.189:55479 I0116 23:04:34.585837 13244 slave.cpp:2031] Flushing queued task 0 for executor 'default' of framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.585922 13252 exec.cpp:221] Executor registered on slave 20150116-230434-3176252227-55479-13226-S0 I0116 23:04:34.587245 13245 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 17.982379ms I0116 23:04:34.587303 13245 leveldb.cpp:401] Deleting ~2 keys from leveldb took 26257ns I0116 23:04:34.587319 13245 replica.cpp:679] Persisted action at 4 I0116 23:04:34.587337 13245 replica.cpp:664] Replica learned TRUNCATE action at position 4 I0116 23:04:34.588016 13252 exec.cpp:233] Executor::registered took 19289ns I0116 23:04:34.588165 13252 exec.cpp:308] Executor asked to run task '0' I0116 23:04:34.588223 13252 exec.cpp:317] Executor::launchTask took 39014ns I0116 23:04:34.589771 13252 exec.cpp:540] Executor sending status update TASK_RUNNING (UUID: b0f73dd4-a5cf-4fe3-866b-32fd3d0a0d93) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.589929 13252 slave.cpp:2265] Handling status update TASK_RUNNING (UUID: b0f73dd4-a5cf-4fe3-866b-32fd3d0a0d93) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 from executor(79)@67.195.81.189:55479 I0116 23:04:34.590109 13248 status_update_manager.cpp:317] Received status update TASK_RUNNING (UUID: b0f73dd4-a5cf-4fe3-866b-32fd3d0a0d93) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.590148 13248 status_update_manager.cpp:494] Creating StatusUpdate stream for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.590360 13248 status_update_manager.cpp:371] Forwarding update TASK_RUNNING (UUID: b0f73dd4-a5cf-4fe3-866b-32fd3d0a0d93) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 to the slave I0116 23:04:34.590652 13243 slave.cpp:2508] Forwarding the update TASK_RUNNING (UUID: b0f73dd4-a5cf-4fe3-866b-32fd3d0a0d93) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 to [email protected]:55479 I0116 23:04:34.590853 13243 master.cpp:3653] Forwarding status update TASK_RUNNING (UUID: b0f73dd4-a5cf-4fe3-866b-32fd3d0a0d93) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.591022 13243 master.cpp:3625] Status update TASK_RUNNING (UUID: b0f73dd4-a5cf-4fe3-866b-32fd3d0a0d93) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 from slave 20150116-230434-3176252227-55479-13226-S0 at slave(223)@67.195.81.189:55479 (proserpina.apache.org) I0116 23:04:34.591025 13248 slave.cpp:2435] Status update manager successfully handled status update TASK_RUNNING (UUID: b0f73dd4-a5cf-4fe3-866b-32fd3d0a0d93) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.591097 13243 master.cpp:4935] Updating the latest state of task 0 of framework 20150116-230434-3176252227-55479-13226-0000 to TASK_RUNNING I0116 23:04:34.591094 13248 slave.cpp:2441] Sending acknowledgement for status update TASK_RUNNING (UUID: b0f73dd4-a5cf-4fe3-866b-32fd3d0a0d93) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 to executor(79)@67.195.81.189:55479 I0116 23:04:34.591179 13252 sched.cpp:696] Scheduler::statusUpdate took 57109ns I0116 23:04:34.591291 13250 exec.cpp:354] Executor received status update acknowledgement b0f73dd4-a5cf-4fe3-866b-32fd3d0a0d93 for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.592926 13247 master.cpp:3126] Forwarding status update acknowledgement b0f73dd4-a5cf-4fe3-866b-32fd3d0a0d93 for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 (default) at [email protected]:55479 to slave 20150116-230434-3176252227-55479-13226-S0 at slave(223)@67.195.81.189:55479 (proserpina.apache.org) I0116 23:04:34.593252 13247 status_update_manager.cpp:389] Received status update acknowledgement (UUID: b0f73dd4-a5cf-4fe3-866b-32fd3d0a0d93) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.593302 13245 slave.cpp:2948] Executor 'default' of framework 20150116-230434-3176252227-55479-13226-0000 exited with status 0 I0116 23:04:34.595790 13245 slave.cpp:2265] Handling status update TASK_LOST (UUID: 0350b61b-b78b-4060-b1f3-d904f3bd3dd0) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 from @0.0.0.0:0 I0116 23:04:34.595876 13245 slave.cpp:4229] Terminating task 0 I0116 23:04:34.598233 13245 slave.cpp:1852] Status update manager successfully handled status update acknowledgement (UUID: b0f73dd4-a5cf-4fe3-866b-32fd3d0a0d93) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.598352 13242 master.cpp:3705] Executor default of framework 20150116-230434-3176252227-55479-13226-0000 on slave 20150116-230434-3176252227-55479-13226-S0 at slave(223)@67.195.81.189:55479 (proserpina.apache.org) exited with status 0 I0116 23:04:34.598462 13242 master.cpp:5023] Removing executor 'default' with resources of framework 20150116-230434-3176252227-55479-13226-0000 on slave 20150116-230434-3176252227-55479-13226-S0 at slave(223)@67.195.81.189:55479 (proserpina.apache.org) I0116 23:04:34.598875 13241 status_update_manager.cpp:317] Received status update TASK_LOST (UUID: 0350b61b-b78b-4060-b1f3-d904f3bd3dd0) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.598973 13241 status_update_manager.cpp:371] Forwarding update TASK_LOST (UUID: 0350b61b-b78b-4060-b1f3-d904f3bd3dd0) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 to the slave I0116 23:04:34.599201 13243 slave.cpp:2508] Forwarding the update TASK_LOST (UUID: 0350b61b-b78b-4060-b1f3-d904f3bd3dd0) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 to [email protected]:55479 I0116 23:04:34.599359 13243 slave.cpp:2435] Status update manager successfully handled status update TASK_LOST (UUID: 0350b61b-b78b-4060-b1f3-d904f3bd3dd0) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.599488 13245 master.cpp:3653] Forwarding status update TASK_LOST (UUID: 0350b61b-b78b-4060-b1f3-d904f3bd3dd0) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.600123 13245 master.cpp:3625] Status update TASK_LOST (UUID: 0350b61b-b78b-4060-b1f3-d904f3bd3dd0) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 from slave 20150116-230434-3176252227-55479-13226-S0 at slave(223)@67.195.81.189:55479 (proserpina.apache.org) I0116 23:04:34.600183 13245 master.cpp:4935] Updating the latest state of task 0 of framework 20150116-230434-3176252227-55479-13226-0000 to TASK_LOST I0116 23:04:34.600206 13248 sched.cpp:696] Scheduler::statusUpdate took 15342ns I0116 23:04:34.600592 13248 hierarchical_allocator_process.hpp:653] Recovered cpus(*):2; mem(*):1024 (total allocatable: disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024) on slave 20150116-230434-3176252227-55479-13226-S0 from framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.600666 13245 master.cpp:4994] Removing task 0 with resources cpus(*):2; mem(*):1024 of framework 20150116-230434-3176252227-55479-13226-0000 on slave 20150116-230434-3176252227-55479-13226-S0 at slave(223)@67.195.81.189:55479 (proserpina.apache.org) I0116 23:04:34.600811 13245 master.cpp:3126] Forwarding status update acknowledgement 0350b61b-b78b-4060-b1f3-d904f3bd3dd0 for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 (default) at [email protected]:55479 to slave 20150116-230434-3176252227-55479-13226-S0 at slave(223)@67.195.81.189:55479 (proserpina.apache.org) I0116 23:04:34.601079 13245 status_update_manager.cpp:389] Received status update acknowledgement (UUID: 0350b61b-b78b-4060-b1f3-d904f3bd3dd0) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.601173 13245 status_update_manager.cpp:525] Cleaning up status update stream for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.601408 13245 slave.cpp:1852] Status update manager successfully handled status update acknowledgement (UUID: 0350b61b-b78b-4060-b1f3-d904f3bd3dd0) for task 0 of framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.601455 13245 slave.cpp:4268] Completing task 0 I0116 23:04:34.601485 13245 slave.cpp:3057] Cleaning up executor 'default' of framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.601690 13243 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_t2Qxkd/slaves/20150116-230434-3176252227-55479-13226-S0/frameworks/20150116-230434-3176252227-55479-13226-0000/executors/default/runs/3594e4eb-14a1-4217-af32-6a0a57fc4329' for gc 6.99999313726815days in the future I0116 23:04:34.601855 13245 slave.cpp:3136] Cleaning up framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.601959 13243 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_t2Qxkd/slaves/20150116-230434-3176252227-55479-13226-S0/frameworks/20150116-230434-3176252227-55479-13226-0000/executors/default' for gc 6.99999313726815days in the future I0116 23:04:34.602010 13248 status_update_manager.cpp:279] Closing status update streams for framework 20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.602303 13247 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_t2Qxkd/slaves/20150116-230434-3176252227-55479-13226-S0/frameworks/20150116-230434-3176252227-55479-13226-0000' for gc 6.99999313726815days in the future I0116 23:04:34.613948 13240 slave.cpp:3373] Current disk usage 90.00%. Max allowed age: 0ns I0116 23:04:34.614205 13250 gc.cpp:167] Pruning directories with remaining removal time 6.99998156319407days I0116 23:04:34.614406 13250 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_t2Qxkd/slaves/20150116-230434-3176252227-55479-13226-S0/frameworks/20150116-230434-3176252227-55479-13226-0000/executors/default/runs/3594e4eb-14a1-4217-af32-6a0a57fc4329 I0116 23:04:34.614405 13248 hierarchical_allocator_process.hpp:746] Performed allocation for 1 slaves in 365148ns I0116 23:04:34.614547 13250 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_t2Qxkd/slaves/20150116-230434-3176252227-55479-13226-S0/frameworks/20150116-230434-3176252227-55479-13226-0000/executors/default/runs/3594e4eb-14a1-4217-af32-6a0a57fc4329' I0116 23:04:34.614665 13245 master.cpp:4072] Sending 1 offers to framework 20150116-230434-3176252227-55479-13226-0000 (default) at [email protected]:55479 I0116 23:04:34.614718 13250 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_t2Qxkd/slaves/20150116-230434-3176252227-55479-13226-S0/frameworks/20150116-230434-3176252227-55479-13226-0000/executors/default I0116 23:04:34.614891 13250 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_t2Qxkd/slaves/20150116-230434-3176252227-55479-13226-S0/frameworks/20150116-230434-3176252227-55479-13226-0000/executors/default' I0116 23:04:34.614940 13250 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_t2Qxkd/slaves/20150116-230434-3176252227-55479-13226-S0/frameworks/20150116-230434-3176252227-55479-13226-0000 I0116 23:04:34.615047 13253 sched.cpp:605] Scheduler::resourceOffers took 16008ns I0116 23:04:34.615092 13250 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_t2Qxkd/slaves/20150116-230434-3176252227-55479-13226-S0/frameworks/20150116-230434-3176252227-55479-13226-0000' I0116 23:04:34.696871 13248 process.cpp:2765] Handling HTTP event for process 'files' with path: '/files/browse.json' I0116 23:04:34.847493 13226 sched.cpp:1471] Asked to stop the driver I0116 23:04:34.847736 13247 sched.cpp:808] Stopping framework '20150116-230434-3176252227-55479-13226-0000' I0116 23:04:34.847745 13226 master.cpp:654] Master terminating I0116 23:04:34.849145 13241 slave.cpp:2673] [email protected]:55479 exited W0116 23:04:34.849176 13241 slave.cpp:2676] Master disconnected! Waiting for a new master to be elected I0116 23:04:34.903384 13226 slave.cpp:495] Slave terminating [ OK ] GarbageCollectorIntegrationTest.DiskUsage (968 ms) [----------] 5 tests from GarbageCollectorIntegrationTest (4811 ms total) [----------] 4 tests from CRAMMD5Authentication/0, where TypeParam = mesos::internal::cram_md5::Authentication<mesos::internal::cram_md5::CRAMMD5Authenticatee, mesos::internal::cram_md5::CRAMMD5Authenticator> [ RUN ] CRAMMD5Authentication/0.success Using temporary directory '/tmp/CRAMMD5Authentication_0_success_l77Fdw' I0116 23:04:34.926285 13249 authenticatee.hpp:138] Creating new client SASL connection I0116 23:04:34.927304 13247 authenticator.hpp:170] Creating new server SASL connection I0116 23:04:34.928282 13240 authenticatee.hpp:229] Received SASL authentication mechanisms: CRAM-MD5 I0116 23:04:34.928320 13240 authenticatee.hpp:255] Attempting to authenticate with mechanism 'CRAM-MD5' I0116 23:04:34.928473 13251 authenticator.hpp:276] Received SASL authentication start I0116 23:04:34.928540 13251 authenticator.hpp:398] Authentication requires more steps I0116 23:04:34.928669 13240 authenticatee.hpp:275] Received SASL authentication step I0116 23:04:34.928956 13252 authenticator.hpp:304] Received SASL authentication step I0116 23:04:34.929038 13252 auxprop.cpp:99] Request to lookup properties for user: 'benh' realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0116 23:04:34.929051 13252 auxprop.cpp:171] Looking up auxiliary property '*userPassword' I0116 23:04:34.929100 13252 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0116 23:04:34.929124 13252 auxprop.cpp:99] Request to lookup properties for user: 'benh' realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0116 23:04:34.929133 13252 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0116 23:04:34.929139 13252 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0116 23:04:34.929154 13252 authenticator.hpp:390] Authentication success I0116 23:04:34.929215 13252 authenticatee.hpp:315] Authentication success [ OK ] CRAMMD5Authentication/0.success (5 ms) [ RUN ] CRAMMD5Authentication/0.failed1 Using temporary directory '/tmp/CRAMMD5Authentication_0_failed1_NhNH7O' I0116 23:04:34.931342 13248 authenticatee.hpp:138] Creating new client SASL connection I0116 23:04:34.937418 13252 authenticator.hpp:170] Creating new server SASL connection I0116 23:04:34.937743 13251 authenticatee.hpp:229] Received SASL authentication mechanisms: CRAM-MD5 I0116 23:04:34.937777 13251 authenticatee.hpp:255] Attempting to authenticate with mechanism 'CRAM-MD5' I0116 23:04:34.937886 13254 authenticator.hpp:276] Received SASL authentication start I0116 23:04:34.937959 13254 authenticator.hpp:398] Authentication requires more steps I0116 23:04:34.938082 13248 authenticatee.hpp:275] Received SASL authentication step I0116 23:04:34.938244 13253 authenticator.hpp:304] Received SASL authentication step I0116 23:04:34.938279 13253 auxprop.cpp:99] Request to lookup properties for user: 'benh' realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0116 23:04:34.938297 13253 auxprop.cpp:171] Looking up auxiliary property '*userPassword' I0116 23:04:34.938349 13253 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0116 23:04:34.938403 13253 auxprop.cpp:99] Request to lookup properties for user: 'benh' realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0116 23:04:34.938432 13253 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0116 23:04:34.938448 13253 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true W0116 23:04:34.938477 13253 authenticator.hpp:404] Authentication failure: authentication failure [ OK ] CRAMMD5Authentication/0.failed1 (13 ms) [ RUN ] CRAMMD5Authentication/0.failed2 Using temporary directory '/tmp/CRAMMD5Authentication_0_failed2_7VyQ37' I0116 23:04:34.944757 13242 authenticatee.hpp:138] Creating new client SASL connection I0116 23:04:34.945909 13240 authenticator.hpp:170] Creating new server SASL connection I0116 23:04:34.946382 13244 authenticatee.hpp:229] Received SASL authentication mechanisms: CRAM-MD5 I0116 23:04:34.946420 13244 authenticatee.hpp:255] Attempting to authenticate with mechanism 'CRAM-MD5' I0116 23:04:34.946511 13244 authenticator.hpp:276] Received SASL authentication start I0116 23:04:34.946568 13244 authenticator.hpp:398] Authentication requires more steps I0116 23:04:34.946648 13244 authenticatee.hpp:275] Received SASL authentication step I0116 23:04:34.946758 13240 authenticator.hpp:304] Received SASL authentication step I0116 23:04:34.946796 13240 auxprop.cpp:99] Request to lookup properties for user: 'benh' realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0116 23:04:34.946810 13240 auxprop.cpp:171] Looking up auxiliary property '*userPassword' I0116 23:04:34.946822 13240 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0116 23:04:34.946836 13240 auxprop.cpp:99] Request to lookup properties for user: 'benh' realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0116 23:04:34.946846 13240 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0116 23:04:34.946854 13240 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true W0116 23:04:34.946866 13240 authenticator.hpp:404] Authentication failure: user not found [ OK ] CRAMMD5Authentication/0.failed2 (5 ms) [ RUN ] CRAMMD5Authentication/0.AuthenticatorDestructionRace Using temporary directory '/tmp/CRAMMD5Authentication_0_AuthenticatorDestructionRace_NxXK0q' I0116 23:04:34.948928 13242 authenticatee.hpp:138] Creating new client SASL connection I0116 23:04:34.950187 13247 authenticator.hpp:170] Creating new server SASL connection I0116 23:04:34.950408 13240 authenticatee.hpp:229] Received SASL authentication mechanisms: CRAM-MD5 I0116 23:04:34.950445 13240 authenticatee.hpp:255] Attempting to authenticate with mechanism 'CRAM-MD5' I0116 23:04:34.950569 13240 authenticator.hpp:276] Received SASL authentication start I0116 23:04:34.950664 13240 authenticator.hpp:398] Authentication requires more steps [ OK ] CRAMMD5Authentication/0.AuthenticatorDestructionRace (13 ms) [----------] 4 tests from CRAMMD5Authentication/0 (36 ms total) [----------] 2 tests from CpuIsolatorTest/2, where TypeParam = mesos::internal::tests::Module<mesos::internal::slave::Isolator, (mesos::internal::tests::ModuleID)1> [ RUN ] CpuIsolatorTest/2.SystemCpuUsage Using temporary directory '/tmp/CpuIsolatorTest_2_SystemCpuUsage_dFLMZJ' I0116 23:04:34.967034 13226 launcher.cpp:137] Forked child with pid '18453' for container 'system_cpu_usage' [ OK ] CpuIsolatorTest/2.SystemCpuUsage (390 ms) [ RUN ] CpuIsolatorTest/2.UserCpuUsage Using temporary directory '/tmp/CpuIsolatorTest_2_UserCpuUsage_D1DoZm' I0116 23:04:35.359274 13226 launcher.cpp:137] Forked child with pid '18462' for container 'user_cpu_usage' [ OK ] CpuIsolatorTest/2.UserCpuUsage (407 ms) [----------] 2 tests from CpuIsolatorTest/2 (797 ms total) [----------] Global test environment tear-down [==========] 508 tests from 80 test cases ran. (441760 ms total) [ PASSED ] 507 tests. [ FAILED ] 1 test, listed below: [ FAILED ] ExamplesTest.LowLevelSchedulerLibprocess 1 FAILED TEST YOU HAVE 7 DISABLED TESTS *** Aborted at 1421449475 (unix time) try "date -d @1421449475" if you are using GNU date *** make[3]: *** [check-local] Error 1 make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'> make[2]: *** [check-am] Error 2 make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'> make[1]: *** [check] Error 2 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'> make: *** [check-recursive] Error 1 Test finished Exit status: 2 Build step 'Execute shell' marked build as failure Recording test results
