Let's try to get stack traces: https://reviews.apache.org/r/29996/
On Fri, Jan 16, 2015 at 3:04 PM, Apache Jenkins Server < [email protected]> wrote: > 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 >
