You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2016/03/02 03:42:27 UTC
Build failed in Jenkins: Mesos » clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1755
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1755/changes>
Changes:
[yujie.jay] Added a check when umounting persistent volumes in docker containerizer.
------------------------------------------
[...truncated 164029 lines...]
I0302 02:35:21.580536 31517 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 4
I0302 02:35:21.581063 31514 slave.cpp:3482] Received ping from slave-observer(411)@172.17.0.2:59579
I0302 02:35:21.581156 31507 master.cpp:4322] Registered slave 298bba19-94a1-44ce-ac0e-e29e33b51715-S0 at slave(443)@172.17.0.2:59579 (804e28b88cfc) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0302 02:35:21.581344 31514 slave.cpp:971] Registered with master master@172.17.0.2:59579; given slave ID 298bba19-94a1-44ce-ac0e-e29e33b51715-S0
I0302 02:35:21.581368 31514 fetcher.cpp:81] Clearing fetcher cache
I0302 02:35:21.581498 31516 hierarchical.cpp:473] Added slave 298bba19-94a1-44ce-ac0e-e29e33b51715-S0 (804e28b88cfc) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: )
I0302 02:35:21.581634 31507 status_update_manager.cpp:181] Resuming sending status updates
I0302 02:35:21.581841 31507 replica.cpp:537] Replica received write request for position 4 from (14638)@172.17.0.2:59579
I0302 02:35:21.582535 31516 hierarchical.cpp:1532] No inverse offers to send out!
I0302 02:35:21.582576 31516 hierarchical.cpp:1150] Performed allocation for slave 298bba19-94a1-44ce-ac0e-e29e33b51715-S0 in 1.03405ms
I0302 02:35:21.583273 31516 master.cpp:5369] Sending 1 offers to framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 (default)
I0302 02:35:21.583400 31514 slave.cpp:994] Checkpointing SlaveInfo to '/tmp/ContentType_SchedulerTest_KillTask_0_dBOQQR/meta/slaves/298bba19-94a1-44ce-ac0e-e29e33b51715-S0/slave.info'
I0302 02:35:21.583741 31507 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.875576ms
I0302 02:35:21.583776 31507 replica.cpp:712] Persisted action at 4
I0302 02:35:21.583904 31514 slave.cpp:1030] Forwarding total oversubscribed resources
I0302 02:35:21.584158 31514 master.cpp:4663] Received update of slave 298bba19-94a1-44ce-ac0e-e29e33b51715-S0 at slave(443)@172.17.0.2:59579 (804e28b88cfc) with total oversubscribed resources
I0302 02:35:21.584939 31514 hierarchical.cpp:531] Slave 298bba19-94a1-44ce-ac0e-e29e33b51715-S0 (804e28b88cfc) updated with oversubscribed resources (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000])
I0302 02:35:21.585208 31514 hierarchical.cpp:1437] No resources available to allocate!
I0302 02:35:21.585260 31514 hierarchical.cpp:1532] No inverse offers to send out!
I0302 02:35:21.585290 31514 hierarchical.cpp:1150] Performed allocation for slave 298bba19-94a1-44ce-ac0e-e29e33b51715-S0 in 291464ns
I0302 02:35:21.586287 31510 replica.cpp:691] Replica received learned notice for position 4 from @0.0.0.0:0
I0302 02:35:21.586585 31512 scheduler.cpp:602] Enqueuing event OFFERS received from http://172.17.0.2:59579/master/api/v1/scheduler
I0302 02:35:21.587729 31510 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 1.422402ms
I0302 02:35:21.587812 31510 leveldb.cpp:399] Deleting ~2 keys from leveldb took 56290ns
I0302 02:35:21.587841 31510 replica.cpp:712] Persisted action at 4
I0302 02:35:21.587870 31510 replica.cpp:697] Replica learned TRUNCATE action at position 4
I0302 02:35:21.591404 31516 scheduler.cpp:230] Sending ACCEPT call to http://172.17.0.2:59579/master/api/v1/scheduler
I0302 02:35:21.593153 31516 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0302 02:35:21.593861 31516 http.cpp:311] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:38753
I0302 02:35:21.595010 31516 master.cpp:3152] Processing ACCEPT call for offers: [ 298bba19-94a1-44ce-ac0e-e29e33b51715-O0 ] on slave 298bba19-94a1-44ce-ac0e-e29e33b51715-S0 at slave(443)@172.17.0.2:59579 (804e28b88cfc) for framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 (default)
I0302 02:35:21.595136 31516 master.cpp:2824] Authorizing framework principal 'test-principal' to launch task 1b456806-d365-443f-a07e-836d7adb1f64 as user 'mesos'
W0302 02:35:21.598428 31516 validation.cpp:404] Executor default for task 1b456806-d365-443f-a07e-836d7adb1f64 uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0302 02:35:21.598601 31516 validation.cpp:416] Executor default for task 1b456806-d365-443f-a07e-836d7adb1f64 uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0302 02:35:21.599287 31516 master.hpp:176] Adding task 1b456806-d365-443f-a07e-836d7adb1f64 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 298bba19-94a1-44ce-ac0e-e29e33b51715-S0 (804e28b88cfc)
I0302 02:35:21.599725 31516 master.cpp:3637] Launching task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 (default) with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 298bba19-94a1-44ce-ac0e-e29e33b51715-S0 at slave(443)@172.17.0.2:59579 (804e28b88cfc)
I0302 02:35:21.600358 31516 slave.cpp:1361] Got assigned task 1b456806-d365-443f-a07e-836d7adb1f64 for framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.601109 31516 slave.cpp:1480] Launching task 1b456806-d365-443f-a07e-836d7adb1f64 for framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.602010 31516 paths.cpp:474] Trying to chown '/tmp/ContentType_SchedulerTest_KillTask_0_dBOQQR/slaves/298bba19-94a1-44ce-ac0e-e29e33b51715-S0/frameworks/298bba19-94a1-44ce-ac0e-e29e33b51715-0000/executors/default/runs/80e8501a-e0b0-4638-a695-90feed5c8c2c' to user 'mesos'
I0302 02:35:21.619048 31516 slave.cpp:5367] Launching executor default of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 with resources in work directory '/tmp/ContentType_SchedulerTest_KillTask_0_dBOQQR/slaves/298bba19-94a1-44ce-ac0e-e29e33b51715-S0/frameworks/298bba19-94a1-44ce-ac0e-e29e33b51715-0000/executors/default/runs/80e8501a-e0b0-4638-a695-90feed5c8c2c'
I0302 02:35:21.621227 31516 executor.cpp:169] Version: 0.28.0
I0302 02:35:21.621793 31516 slave.cpp:1698] Queuing task '1b456806-d365-443f-a07e-836d7adb1f64' for executor 'default' of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.622009 31516 slave.cpp:749] Successfully attached file '/tmp/ContentType_SchedulerTest_KillTask_0_dBOQQR/slaves/298bba19-94a1-44ce-ac0e-e29e33b51715-S0/frameworks/298bba19-94a1-44ce-ac0e-e29e33b51715-0000/executors/default/runs/80e8501a-e0b0-4638-a695-90feed5c8c2c'
I0302 02:35:21.623759 31522 executor.cpp:328] Connected with the agent
I0302 02:35:21.624542 31521 executor.cpp:259] Sending SUBSCRIBE call to http://172.17.0.2:59579/slave(443)/api/v1/executor
I0302 02:35:21.625967 31508 process.cpp:3141] Handling HTTP event for process 'slave(443)' with path: '/slave(443)/api/v1/executor'
I0302 02:35:21.626516 31513 http.cpp:190] HTTP POST for /slave(443)/api/v1/executor from 172.17.0.2:38754
I0302 02:35:21.626801 31513 slave.cpp:2476] Received Subscribe request for HTTP executor 'default' of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.628060 31513 slave.cpp:1863] Sending queued task '1b456806-d365-443f-a07e-836d7adb1f64' to executor 'default' of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 (via HTTP)
I0302 02:35:21.630914 31507 executor.cpp:600] Enqueuing event SUBSCRIBED received from http://172.17.0.2:59579/slave(443)/api/v1/executor
I0302 02:35:21.631752 31521 executor.cpp:600] Enqueuing event LAUNCH received from http://172.17.0.2:59579/slave(443)/api/v1/executor
I0302 02:35:21.632678 31518 executor.cpp:259] Sending UPDATE call to http://172.17.0.2:59579/slave(443)/api/v1/executor
I0302 02:35:21.634166 31517 process.cpp:3141] Handling HTTP event for process 'slave(443)' with path: '/slave(443)/api/v1/executor'
I0302 02:35:21.634845 31517 http.cpp:190] HTTP POST for /slave(443)/api/v1/executor from 172.17.0.2:38755
I0302 02:35:21.635144 31517 slave.cpp:3002] Handling status update TASK_RUNNING (UUID: e3df30b4-8d96-41f4-8ca5-a7295513530b) for task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.636224 31517 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: e3df30b4-8d96-41f4-8ca5-a7295513530b) for task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.636713 31517 status_update_manager.cpp:497] Creating StatusUpdate stream for task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.637282 31517 status_update_manager.cpp:374] Forwarding update TASK_RUNNING (UUID: e3df30b4-8d96-41f4-8ca5-a7295513530b) for task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 to the slave
I0302 02:35:21.638434 31517 slave.cpp:3400] Forwarding the update TASK_RUNNING (UUID: e3df30b4-8d96-41f4-8ca5-a7295513530b) for task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 to master@172.17.0.2:59579
I0302 02:35:21.638782 31517 slave.cpp:3294] Status update manager successfully handled status update TASK_RUNNING (UUID: e3df30b4-8d96-41f4-8ca5-a7295513530b) for task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.639258 31517 master.cpp:4808] Status update TASK_RUNNING (UUID: e3df30b4-8d96-41f4-8ca5-a7295513530b) for task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 from slave 298bba19-94a1-44ce-ac0e-e29e33b51715-S0 at slave(443)@172.17.0.2:59579 (804e28b88cfc)
I0302 02:35:21.639300 31517 master.cpp:4856] Forwarding status update TASK_RUNNING (UUID: e3df30b4-8d96-41f4-8ca5-a7295513530b) for task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.639706 31517 master.cpp:6464] Updating the state of task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0302 02:35:21.641407 31517 executor.cpp:600] Enqueuing event ACKNOWLEDGED received from http://172.17.0.2:59579/slave(443)/api/v1/executor
I0302 02:35:21.642751 31522 scheduler.cpp:602] Enqueuing event UPDATE received from http://172.17.0.2:59579/master/api/v1/scheduler
I0302 02:35:21.644672 31513 scheduler.cpp:230] Sending ACKNOWLEDGE call to http://172.17.0.2:59579/master/api/v1/scheduler
I0302 02:35:21.645064 31513 scheduler.cpp:230] Sending KILL call to http://172.17.0.2:59579/master/api/v1/scheduler
I0302 02:35:21.645645 31508 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0302 02:35:21.646129 31508 http.cpp:311] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:38753
I0302 02:35:21.646256 31508 master.cpp:3966] Processing ACKNOWLEDGE call e3df30b4-8d96-41f4-8ca5-a7295513530b for task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 (default) on slave 298bba19-94a1-44ce-ac0e-e29e33b51715-S0
I0302 02:35:21.647815 31521 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0302 02:35:21.648371 31513 http.cpp:311] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:38753
I0302 02:35:21.648645 31513 master.cpp:3874] Telling slave 298bba19-94a1-44ce-ac0e-e29e33b51715-S0 at slave(443)@172.17.0.2:59579 (804e28b88cfc) to kill task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 (default)
I0302 02:35:21.649509 31513 slave.cpp:1891] Asked to kill task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.650810 31522 status_update_manager.cpp:392] Received status update acknowledgement (UUID: e3df30b4-8d96-41f4-8ca5-a7295513530b) for task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.651079 31519 executor.cpp:600] Enqueuing event KILL received from http://172.17.0.2:59579/slave(443)/api/v1/executor
I0302 02:35:21.651252 31507 slave.cpp:2412] Status update manager successfully handled status update acknowledgement (UUID: e3df30b4-8d96-41f4-8ca5-a7295513530b) for task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.652518 31519 executor.cpp:259] Sending UPDATE call to http://172.17.0.2:59579/slave(443)/api/v1/executor
I0302 02:35:21.653764 31519 process.cpp:3141] Handling HTTP event for process 'slave(443)' with path: '/slave(443)/api/v1/executor'
I0302 02:35:21.654424 31519 http.cpp:190] HTTP POST for /slave(443)/api/v1/executor from 172.17.0.2:38755
I0302 02:35:21.654701 31519 slave.cpp:3002] Handling status update TASK_KILLED (UUID: b1e961f2-b975-4080-a4d1-be66a9abc241) for task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.655370 31519 slave.cpp:5677] Terminating task 1b456806-d365-443f-a07e-836d7adb1f64
I0302 02:35:21.657269 31519 status_update_manager.cpp:320] Received status update TASK_KILLED (UUID: b1e961f2-b975-4080-a4d1-be66a9abc241) for task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.657450 31519 status_update_manager.cpp:374] Forwarding update TASK_KILLED (UUID: b1e961f2-b975-4080-a4d1-be66a9abc241) for task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 to the slave
I0302 02:35:21.657872 31518 slave.cpp:3400] Forwarding the update TASK_KILLED (UUID: b1e961f2-b975-4080-a4d1-be66a9abc241) for task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 to master@172.17.0.2:59579
I0302 02:35:21.658138 31518 slave.cpp:3294] Status update manager successfully handled status update TASK_KILLED (UUID: b1e961f2-b975-4080-a4d1-be66a9abc241) for task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.658447 31520 master.cpp:4808] Status update TASK_KILLED (UUID: b1e961f2-b975-4080-a4d1-be66a9abc241) for task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 from slave 298bba19-94a1-44ce-ac0e-e29e33b51715-S0 at slave(443)@172.17.0.2:59579 (804e28b88cfc)
I0302 02:35:21.658499 31520 master.cpp:4856] Forwarding status update TASK_KILLED (UUID: b1e961f2-b975-4080-a4d1-be66a9abc241) for task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.659530 31515 executor.cpp:600] Enqueuing event ACKNOWLEDGED received from http://172.17.0.2:59579/slave(443)/api/v1/executor
I0302 02:35:21.660894 31520 master.cpp:6464] Updating the state of task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0302 02:35:21.661774 31518 hierarchical.cpp:890] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: ) on slave 298bba19-94a1-44ce-ac0e-e29e33b51715-S0 from framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.662511 31518 scheduler.cpp:602] Enqueuing event UPDATE received from http://172.17.0.2:59579/master/api/v1/scheduler
I0302 02:35:21.664043 31489 master.cpp:1026] Master terminating
I0302 02:35:21.664189 31489 master.cpp:6530] Removing task 1b456806-d365-443f-a07e-836d7adb1f64 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 on slave 298bba19-94a1-44ce-ac0e-e29e33b51715-S0 at slave(443)@172.17.0.2:59579 (804e28b88cfc)
I0302 02:35:21.664592 31489 master.cpp:6559] Removing executor 'default' with resources of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 on slave 298bba19-94a1-44ce-ac0e-e29e33b51715-S0 at slave(443)@172.17.0.2:59579 (804e28b88cfc)
I0302 02:35:21.664654 31513 hierarchical.cpp:505] Removed slave 298bba19-94a1-44ce-ac0e-e29e33b51715-S0
I0302 02:35:21.665882 31512 hierarchical.cpp:326] Removed framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.666185 31512 slave.cpp:3528] master@172.17.0.2:59579 exited
W0302 02:35:21.666210 31512 slave.cpp:3531] Master disconnected! Waiting for a new master to be elected
E0302 02:35:21.669853 31516 scheduler.cpp:575] End-Of-File received from master. The master closed the event stream
I0302 02:35:21.671751 31507 slave.cpp:3886] Executor 'default' of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 exited with status 0
I0302 02:35:21.671936 31507 slave.cpp:668] Slave terminating
I0302 02:35:21.672013 31507 slave.cpp:2079] Asked to shut down framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 by @0.0.0.0:0
I0302 02:35:21.672045 31507 slave.cpp:2104] Shutting down framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.672111 31507 slave.cpp:3990] Cleaning up executor 'default' of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000 (via HTTP)
I0302 02:35:21.672490 31507 slave.cpp:4078] Cleaning up framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.672487 31517 gc.cpp:54] Scheduling '/tmp/ContentType_SchedulerTest_KillTask_0_dBOQQR/slaves/298bba19-94a1-44ce-ac0e-e29e33b51715-S0/frameworks/298bba19-94a1-44ce-ac0e-e29e33b51715-0000/executors/default/runs/80e8501a-e0b0-4638-a695-90feed5c8c2c' for gc 6.99999221915556days in the future
I0302 02:35:21.672775 31517 gc.cpp:54] Scheduling '/tmp/ContentType_SchedulerTest_KillTask_0_dBOQQR/slaves/298bba19-94a1-44ce-ac0e-e29e33b51715-S0/frameworks/298bba19-94a1-44ce-ac0e-e29e33b51715-0000/executors/default' for gc 6.9999922173037days in the future
I0302 02:35:21.673086 31516 scheduler.cpp:414] Re-detecting master
I0302 02:35:21.673382 31509 status_update_manager.cpp:282] Closing status update streams for framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.673461 31509 status_update_manager.cpp:528] Cleaning up status update stream for task 1b456806-d365-443f-a07e-836d7adb1f64 of framework 298bba19-94a1-44ce-ac0e-e29e33b51715-0000
I0302 02:35:21.673745 31517 gc.cpp:54] Scheduling '/tmp/ContentType_SchedulerTest_KillTask_0_dBOQQR/slaves/298bba19-94a1-44ce-ac0e-e29e33b51715-S0/frameworks/298bba19-94a1-44ce-ac0e-e29e33b51715-0000' for gc 6.99999221532444days in the future
F0302 02:35:21.673745 31509 logging.cpp:64] RAW: Pure virtual method called
[ OK ] ContentType/SchedulerTest.KillTask/0 (218 ms)
[ RUN ] ContentType/SchedulerTest.KillTask/1
I0302 02:35:21.685395 31489 leveldb.cpp:174] Opened db in 2.532671ms
I0302 02:35:21.686086 31489 leveldb.cpp:181] Compacted db in 653605ns
I0302 02:35:21.686138 31489 leveldb.cpp:196] Created db iterator in 35160ns
I0302 02:35:21.686162 31489 leveldb.cpp:202] Seeked to beginning of db in 10936ns
I0302 02:35:21.686179 31489 leveldb.cpp:271] Iterated through 0 keys in the db in 9377ns
I0302 02:35:21.686241 31489 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0302 02:35:21.686900 31518 recover.cpp:447] Starting replica recovery
I0302 02:35:21.687237 31516 recover.cpp:473] Replica is in EMPTY status
I0302 02:35:21.688563 31514 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (14648)@172.17.0.2:59579
I0302 02:35:21.688943 31516 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0302 02:35:21.689586 31508 recover.cpp:564] Updating replica status to STARTING
I0302 02:35:21.690402 31507 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 696693ns
I0302 02:35:21.690438 31507 replica.cpp:320] Persisted replica status to STARTING
I0302 02:35:21.690616 31508 recover.cpp:473] Replica is in STARTING status
I0302 02:35:21.691741 31510 master.cpp:375] Master 420cda08-850e-4d3a-b9a2-306902917b4e (804e28b88cfc) started on 172.17.0.2:59579
I0302 02:35:21.691779 31510 master.cpp:377] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/rzvF6h/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.28.0/_inst/share/mesos/webui" --work_dir="/tmp/rzvF6h/master" --zk_session_timeout="10secs"
I0302 02:35:21.692184 31510 master.cpp:424] Master allowing unauthenticated frameworks to register
I0302 02:35:21.692198 31510 master.cpp:427] Master only allowing authenticated slaves to register
I0302 02:35:21.692208 31510 credentials.hpp:35] Loading credentials for authentication from '/tmp/rzvF6h/credentials'
I0302 02:35:21.692589 31510 master.cpp:467] Using default 'crammd5' authenticator
I0302 02:35:21.692677 31513 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (14649)@172.17.0.2:59579
I0302 02:35:21.693771 31515 recover.cpp:193] Received a recover response from a replica in STARTING status
I0302 02:35:21.692790 31510 master.cpp:536] Using default 'basic' HTTP authenticator
I0302 02:35:21.697978 31517 recover.cpp:564] Updating replica status to VOTING
I0302 02:35:21.698264 31510 master.cpp:570] Authorization enabled
I0302 02:35:21.698765 31515 hierarchical.cpp:144] Initialized hierarchical allocator process
I0302 02:35:21.699229 31517 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 656178ns
I0302 02:35:21.699405 31517 replica.cpp:320] Persisted replica status to VOTING
I0302 02:35:21.698767 31514 whitelist_watcher.cpp:77] No whitelist given
I0302 02:35:21.700383 31515 recover.cpp:578] Successfully joined the Paxos group
I0302 02:35:21.701244 31515 recover.cpp:462] Recover process terminated
I0302 02:35:21.701885 31515 master.cpp:1711] The newly elected leader is master@172.17.0.2:59579 with id 420cda08-850e-4d3a-b9a2-306902917b4e
I0302 02:35:21.702072 31515 master.cpp:1724] Elected as the leading master!
I0302 02:35:21.702256 31515 master.cpp:1469] Recovering from registrar
I0302 02:35:21.702746 31515 registrar.cpp:307] Recovering registrar
@ 0x2adce56226cd google::LogMessage::Fail()
I0302 02:35:21.704169 31510 log.cpp:659] Attempting to start the writer
I0302 02:35:21.705636 31511 replica.cpp:493] Replica received implicit promise request from (14651)@172.17.0.2:59579 with proposal 1
I0302 02:35:21.706449 31511 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 586858ns
I0302 02:35:21.706625 31511 replica.cpp:342] Persisted promised to 1
I0302 02:35:21.707851 31513 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0302 02:35:21.709560 31521 replica.cpp:388] Replica received explicit promise request from (14652)@172.17.0.2:59579 for position 0 with proposal 2
I0302 02:35:21.710212 31521 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 617810ns
I0302 02:35:21.710244 31521 replica.cpp:712] Persisted action at 0
@ 0x2adce562a290 google::RawLog__()
I0302 02:35:21.711638 31521 replica.cpp:537] Replica received write request for position 0 from (14653)@172.17.0.2:59579
I0302 02:35:21.711714 31521 leveldb.cpp:436] Reading position from leveldb took 51301ns
I0302 02:35:21.712159 31521 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 410796ns
I0302 02:35:21.712185 31521 replica.cpp:712] Persisted action at 0
I0302 02:35:21.712888 31517 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0302 02:35:21.713443 31517 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 537472ns
I0302 02:35:21.713474 31517 replica.cpp:712] Persisted action at 0
I0302 02:35:21.713495 31517 replica.cpp:697] Replica learned NOP action at position 0
I0302 02:35:21.714442 31517 log.cpp:675] Writer started with ending position 0
I0302 02:35:21.715579 31518 leveldb.cpp:436] Reading position from leveldb took 58708ns
I0302 02:35:21.716850 31517 registrar.cpp:340] Successfully fetched the registry (0B) in 13.917184ms
I0302 02:35:21.716991 31517 registrar.cpp:439] Applied 1 operations in 50194ns; attempting to update the 'registry'
I0302 02:35:21.717736 31514 log.cpp:683] Attempting to append 170 bytes to the log
I0302 02:35:21.718053 31517 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
@ 0x2adce4431ac8 __cxa_pure_virtual
I0302 02:35:21.719205 31514 replica.cpp:537] Replica received write request for position 1 from (14654)@172.17.0.2:59579
I0302 02:35:21.719941 31514 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 583401ns
I0302 02:35:21.720096 31514 replica.cpp:712] Persisted action at 1
I0302 02:35:21.721097 31516 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0302 02:35:21.721601 31516 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 364076ns
I0302 02:35:21.721760 31516 replica.cpp:712] Persisted action at 1
I0302 02:35:21.721946 31516 replica.cpp:697] Replica learned APPEND action at position 1
I0302 02:35:21.723539 31513 registrar.cpp:484] Successfully updated the 'registry' in 6.458112ms
I0302 02:35:21.723758 31522 log.cpp:702] Attempting to truncate the log to 1
I0302 02:35:21.723913 31513 registrar.cpp:370] Successfully recovered registrar
I0302 02:35:21.723904 31519 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0302 02:35:21.725376 31513 replica.cpp:537] Replica received write request for position 2 from (14655)@172.17.0.2:59579
I0302 02:35:21.725744 31522 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0302 02:35:21.725978 31513 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 564577ns
I0302 02:35:21.726013 31513 replica.cpp:712] Persisted action at 2
I0302 02:35:21.725936 31518 master.cpp:1521] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
@ 0x18c7acf testing::internal::UntypedFunctionMockerBase::UntypedInvokeWith()
I0302 02:35:21.727483 31512 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0302 02:35:21.727972 31512 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 465002ns
I0302 02:35:21.728035 31512 leveldb.cpp:399] Deleting ~1 keys from leveldb took 45827ns
I0302 02:35:21.728060 31512 replica.cpp:712] Persisted action at 2
I0302 02:35:21.728091 31512 replica.cpp:697] Replica learned TRUNCATE action at position 2
@ 0xf3f0a8 _ZN7testing8internal18FunctionMockerBaseIFvvEE10InvokeWithERKSt5tupleIJEE
@ 0xf3f079 testing::internal::FunctionMocker<>::Invoke()
@ 0x1417f85 mesos::internal::tests::SchedulerTest::Callbacks::disconnected()
@ 0x1467c52 _ZNKSt7_Mem_fnIMN5mesos8internal5tests13SchedulerTest9CallbacksEFvvEEclIJEvEEvRS4_DpOT_
@ 0x1467bd3 _ZNSt5_BindIFSt7_Mem_fnIMN5mesos8internal5tests13SchedulerTest9CallbacksEFvvEESt17reference_wrapperIS5_EEE6__callIvJEJLm0EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
@ 0x1467b86 _ZNSt5_BindIFSt7_Mem_fnIMN5mesos8internal5tests13SchedulerTest9CallbacksEFvvEESt17reference_wrapperIS5_EEEclIJEvEET0_DpOT_
@ 0x146788d std::_Function_handler<>::_M_invoke()
@ 0x84246e std::function<>::operator()()
@ 0x2adce435ba4d process::AsyncExecutorProcess::execute<>()
@ 0x2adce435c3a7 _ZZN7process8dispatchI7NothingNS_20AsyncExecutorProcessERKSt8functionIFvvEEPvS5_S8_EENS_6FutureIT_EERKNS_3PIDIT0_EEMSD_FSA_T1_T2_ET3_T4_ENKUlPNS_11ProcessBaseEE_clESO_
@ 0x2adce435bfa2 _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchI7NothingNS0_20AsyncExecutorProcessERKSt8functionIFvvEEPvS9_SC_EENS0_6FutureIT_EERKNS0_3PIDIT0_EEMSH_FSE_T1_T2_ET3_T4_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_
@ 0x2adce557f9e8 std::function<>::operator()()
@ 0x2adce5569a04 process::ProcessBase::visit()
@ 0x2adce55c00ae process::DispatchEvent::visit()
@ 0x85e761 process::ProcessBase::serve()
@ 0x2adce5567724 process::ProcessManager::resume()
@ 0x2adce556f995 process::ProcessManager::init_threads()::$_1::operator()()
@ 0x2adce556f8d3 _ZNSt5_BindIFZN7process14ProcessManager12init_threadsEvE3$_1St17reference_wrapperIKSt11atomic_boolEEE6__callIvJEJLm0EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
@ 0x2adce556f886 _ZNSt5_BindIFZN7process14ProcessManager12init_threadsEvE3$_1St17reference_wrapperIKSt11atomic_boolEEEclIJEvEET0_DpOT_
@ 0x2adce556f835 _ZNSt12_Bind_simpleIFSt5_BindIFZN7process14ProcessManager12init_threadsEvE3$_1St17reference_wrapperIKSt11atomic_boolEEEvEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE
@ 0x2adce556f805 std::_Bind_simple<>::operator()()
@ 0x2adce556f7dc std::thread::_Impl<>::_M_run()
@ 0x2adce6d3ba60 (unknown)
@ 0x2adce74b2182 start_thread
@ 0x2adce77c247d (unknown)
make[4]: *** [check-local] Aborted
make[4]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[3]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[3]: *** [check-am] Error 2
make[2]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[2]: *** [check] Error 2
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.28.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1456884189-5705
Untagged: mesos-1456884189-5705:latest
Deleted: 20cdc7f31bd157fc71f617eee2f444dee0ca99699c0ec8e7f541297c32922dcf
Deleted: 3a8373132ecd18c0da47b07d58076e60b90ad4f8a01bc5e9c2879b30a9cb5262
Deleted: 4f5bdf3c5a8eb5faefc0b799e0b925a02be34d12da1b2cae1c14283f2beacbf3
Deleted: 590136b749687c0f340fbf06ed7717d0203ba9891d2d8275bff8ace801226760
Deleted: cf154bd090abb53a1510bd5633bfcd462dcb2914ee4c4c3980e4e3d6c15786a1
Deleted: c2a87a3b07e3024392bddee377aac73b62ab5d8c8cb89d9dc9ae284cd2b878b3
Deleted: f9cdb0382c0c238ffeaca8161ccf02dc8ae2db646b97b40c74bca904a3273054
Deleted: c53f9ac8d19f7278a2135154e98ce6d91d87707b8852a190f15fb8789332cc60
Deleted: 786ef9b997793cd7879f29259609e0dd9694ca450a909fc87b6371468c11163a
Deleted: 61f662f57b195ba1bffac771d84e16134a17fb6171227ea6c139f12be921cb0a
Deleted: 8d93a0382a85109cce27859549ec47bbf34b6f768f59f9cce5fe4279ae6306fc
Deleted: c74315ba7029e20f9f90291188a2c3dfc681fa19d0ccd065b3a670e4856d4e4c
Deleted: 02f0b017701709c40313ec3ecc22a69b6c114af3a9c812dff7dc52e53d67733a
Deleted: 752ab606c27976be55eb85cb536514abffa3772970b8e3c211d2782a9f27a2cf
Deleted: aeeeb3afc6d6043b6dc475aaa5d5bbfc76983ec3b7d4b82bd59ae5402e2310ba
Deleted: d9606513162791835e23eaf726b947d6dc356ba26f076be6142823386f4800f9
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos » clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1756
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1756/changes>