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 2017/09/18 09:48:47 UTC

Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4235

See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4235/display/redirect?page=changes>

Changes:

[bbannier] Added a `[-s|--skip-hooks]` option when applying reviews.

------------------------------------------
[...truncated 24.12 MB...]
3: I0918 09:48:28.330060 16717 master.cpp:8993] Updating the state of task 03b03844-0431-4833-85d2-320a3e6bc0e2 of framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000 (latest state: TASK_FINISHED, status update state: TASK_FINISHED)
3: I0918 09:48:28.330530 16722 sched.cpp:1041] Scheduler::statusUpdate took 162959ns
3: I0918 09:48:28.330997 16719 slave.cpp:4769] Status update manager successfully handled status update TASK_FINISHED (UUID: 67f5aad7-5d85-4066-a272-d9616c76928a) for task 03b03844-0431-4833-85d2-320a3e6bc0e2 of framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000
3: I0918 09:48:28.331090 16719 slave.cpp:4785] Sending acknowledgement for status update TASK_FINISHED (UUID: 67f5aad7-5d85-4066-a272-d9616c76928a) for task 03b03844-0431-4833-85d2-320a3e6bc0e2 of framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000 to executor(180)@172.17.0.2:34977
3: I0918 09:48:28.331356 16719 exec.cpp:388] Executor received status update acknowledgement 67f5aad7-5d85-4066-a272-d9616c76928a for task 03b03844-0431-4833-85d2-320a3e6bc0e2 of framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000
3: I0918 09:48:28.331341 16717 master.cpp:5534] Processing ACKNOWLEDGE call 67f5aad7-5d85-4066-a272-d9616c76928a for task 03b03844-0431-4833-85d2-320a3e6bc0e2 of framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000 (default) at scheduler-9cfe8779-7d10-43ca-999e-2ae65d53990d@172.17.0.2:34977 on agent e198faad-dba3-4185-8bfa-b0492b0fa742-S0
3: I0918 09:48:28.331456 16717 master.cpp:9087] Removing task 03b03844-0431-4833-85d2-320a3e6bc0e2 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] of framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000 on agent e198faad-dba3-4185-8bfa-b0492b0fa742-S0 at slave(616)@172.17.0.2:34977 (cec572d5f5ff)
3: I0918 09:48:28.332610 16720 hierarchical.cpp:1161] Recovered cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent e198faad-dba3-4185-8bfa-b0492b0fa742-S0 from framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000
3: I0918 09:48:28.333024 16718 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 67f5aad7-5d85-4066-a272-d9616c76928a) for task 03b03844-0431-4833-85d2-320a3e6bc0e2 of framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000
3: I0918 09:48:28.333257 16718 status_update_manager.cpp:531] Cleaning up status update stream for task 03b03844-0431-4833-85d2-320a3e6bc0e2 of framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000
3: I0918 09:48:28.333866 16717 slave.cpp:3678] Status update manager successfully handled status update acknowledgement (UUID: 67f5aad7-5d85-4066-a272-d9616c76928a) for task 03b03844-0431-4833-85d2-320a3e6bc0e2 of framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000
3: I0918 09:48:28.333966 16717 slave.cpp:7794] Completing task 03b03844-0431-4833-85d2-320a3e6bc0e2
3: I0918 09:48:28.336545 16723 process.cpp:3929] Handling HTTP event for process 'master' with path: '/master/api/v1'
3: I0918 09:48:28.338783 16724 http.cpp:1185] HTTP POST for /master/api/v1 from 172.17.0.2:49792
3: I0918 09:48:28.339056 16724 http.cpp:673] Processing call GET_STATE
3: I0918 09:48:28.356516 16716 sched.cpp:2021] Asked to stop the driver
3: I0918 09:48:28.356736 16720 sched.cpp:1203] Stopping framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000
3: I0918 09:48:28.357352 16723 master.cpp:8406] Processing TEARDOWN call for framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000 (default) at scheduler-9cfe8779-7d10-43ca-999e-2ae65d53990d@172.17.0.2:34977
3: I0918 09:48:28.357425 16723 master.cpp:8418] Removing framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000 (default) at scheduler-9cfe8779-7d10-43ca-999e-2ae65d53990d@172.17.0.2:34977
3: I0918 09:48:28.357477 16723 master.cpp:3267] Deactivating framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000 (default) at scheduler-9cfe8779-7d10-43ca-999e-2ae65d53990d@172.17.0.2:34977
3: I0918 09:48:28.357810 16722 hierarchical.cpp:412] Deactivated framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000
3: I0918 09:48:28.358009 16723 master.cpp:9116] Removing executor 'default' with resources [] of framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000 on agent e198faad-dba3-4185-8bfa-b0492b0fa742-S0 at slave(616)@172.17.0.2:34977 (cec572d5f5ff)
3: I0918 09:48:28.358084 16722 slave.cpp:3210] Asked to shut down framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000 by master@172.17.0.2:34977
3: I0918 09:48:28.358136 16722 slave.cpp:3235] Shutting down framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000
3: I0918 09:48:28.358206 16722 slave.cpp:5731] Shutting down executor 'default' of framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000 at executor(180)@172.17.0.2:34977
3: I0918 09:48:28.358410 16722 exec.cpp:435] Executor asked to shutdown
3: I0918 09:48:28.358449 16722 exec.cpp:450] Executor::shutdown took 29895ns
3: I0918 09:48:28.359011 16722 slave.cpp:5007] Got exited event for executor(180)@172.17.0.2:34977
3: I0918 09:48:28.359658 16723 slave.cpp:5407] Executor 'default' of framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000 exited with status 0
3: I0918 09:48:28.359899 16723 slave.cpp:5511] Cleaning up executor 'default' of framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000 at executor(180)@172.17.0.2:34977
3: I0918 09:48:28.360416 16722 hierarchical.cpp:355] Removed framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000
3: I0918 09:48:28.360505 16722 gc.cpp:91] Scheduling '/tmp/ContentType_MasterAPITest_GetState_1_ZoHzK1/slaves/e198faad-dba3-4185-8bfa-b0492b0fa742-S0/frameworks/e198faad-dba3-4185-8bfa-b0492b0fa742-0000/executors/default/runs/3db04ea0-bef7-4655-b676-3706996de836' for gc 6.99999583162074days in the future
3: I0918 09:48:28.360705 16723 slave.cpp:5607] Cleaning up framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000
3: I0918 09:48:28.360908 16720 status_update_manager.cpp:285] Closing status update streams for framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000
3: I0918 09:48:28.360954 16722 gc.cpp:91] Scheduling '/tmp/ContentType_MasterAPITest_GetState_1_ZoHzK1/slaves/e198faad-dba3-4185-8bfa-b0492b0fa742-S0/frameworks/e198faad-dba3-4185-8bfa-b0492b0fa742-0000/executors/default' for gc 6.99999582744296days in the future
3: W0918 09:48:28.361174 16718 master.cpp:7021] Ignoring unknown exited executor 'default' of framework e198faad-dba3-4185-8bfa-b0492b0fa742-0000 on agent e198faad-dba3-4185-8bfa-b0492b0fa742-S0 at slave(616)@172.17.0.2:34977 (cec572d5f5ff)
3: I0918 09:48:28.361295 16722 gc.cpp:91] Scheduling '/tmp/ContentType_MasterAPITest_GetState_1_ZoHzK1/slaves/e198faad-dba3-4185-8bfa-b0492b0fa742-S0/frameworks/e198faad-dba3-4185-8bfa-b0492b0fa742-0000' for gc 6.99999582288296days in the future
3: I0918 09:48:28.361894 16716 slave.cpp:861] Agent terminating
3: I0918 09:48:28.362164 16723 master.cpp:1321] Agent e198faad-dba3-4185-8bfa-b0492b0fa742-S0 at slave(616)@172.17.0.2:34977 (cec572d5f5ff) disconnected
3: I0918 09:48:28.362212 16723 master.cpp:3304] Disconnecting agent e198faad-dba3-4185-8bfa-b0492b0fa742-S0 at slave(616)@172.17.0.2:34977 (cec572d5f5ff)
3: I0918 09:48:28.362489 16723 master.cpp:3323] Deactivating agent e198faad-dba3-4185-8bfa-b0492b0fa742-S0 at slave(616)@172.17.0.2:34977 (cec572d5f5ff)
3: I0918 09:48:28.362782 16717 hierarchical.cpp:690] Agent e198faad-dba3-4185-8bfa-b0492b0fa742-S0 deactivated
3: I0918 09:48:28.366998 16722 master.cpp:1163] Master terminating
3: I0918 09:48:28.368192 16724 hierarchical.cpp:626] Removed agent e198faad-dba3-4185-8bfa-b0492b0fa742-S0
3: [       OK ] ContentType/MasterAPITest.GetState/1 (220 ms)
3: [ RUN      ] ContentType/MasterAPITest.GetTasksNoRunningTask/0
3: I0918 09:48:28.377235 16716 cluster.cpp:162] Creating default 'local' authorizer
3: I0918 09:48:28.381887 16717 master.cpp:445] Master fd281f28-6a10-4b3e-be2b-29072c4843b1 (cec572d5f5ff) started on 172.17.0.2:34977
3: I0918 09:48:28.381964 16717 master.cpp:447] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/PzNUvU/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/PzNUvU/master" --zk_session_timeout="10secs"
3: I0918 09:48:28.382558 16717 master.cpp:497] Master only allowing authenticated frameworks to register
3: I0918 09:48:28.382580 16717 master.cpp:511] Master only allowing authenticated agents to register
3: I0918 09:48:28.382589 16717 master.cpp:524] Master only allowing authenticated HTTP frameworks to register
3: I0918 09:48:28.382601 16717 credentials.hpp:37] Loading credentials for authentication from '/tmp/PzNUvU/credentials'
3: I0918 09:48:28.383103 16717 master.cpp:569] Using default 'crammd5' authenticator
3: I0918 09:48:28.383371 16717 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I0918 09:48:28.383599 16717 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I0918 09:48:28.383815 16717 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I0918 09:48:28.384006 16717 master.cpp:649] Authorization enabled
3: I0918 09:48:28.384243 16722 hierarchical.cpp:171] Initialized hierarchical allocator process
3: I0918 09:48:28.384279 16723 whitelist_watcher.cpp:77] No whitelist given
3: I0918 09:48:28.388169 16720 master.cpp:2166] Elected as the leading master!
3: I0918 09:48:28.388243 16720 master.cpp:1705] Recovering from registrar
3: I0918 09:48:28.388553 16723 registrar.cpp:347] Recovering registrar
3: I0918 09:48:28.389405 16723 registrar.cpp:391] Successfully fetched the registry (0B) in 792064ns
3: I0918 09:48:28.389557 16723 registrar.cpp:495] Applied 1 operations in 51910ns; attempting to update the registry
3: I0918 09:48:28.390429 16721 registrar.cpp:552] Successfully updated the registry in 685824ns
3: I0918 09:48:28.390607 16721 registrar.cpp:424] Successfully recovered registrar
3: I0918 09:48:28.391263 16721 master.cpp:1804] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I0918 09:48:28.391512 16720 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
3: I0918 09:48:28.394791 16718 process.cpp:3929] Handling HTTP event for process 'master' with path: '/master/api/v1'
3: I0918 09:48:28.397240 16718 http.cpp:1185] HTTP POST for /master/api/v1 from 172.17.0.2:49794
3: I0918 09:48:28.397370 16718 http.cpp:673] Processing call GET_TASKS
3: I0918 09:48:28.418609 16716 master.cpp:1163] Master terminating
3: [       OK ] ContentType/MasterAPITest.GetTasksNoRunningTask/0 (50 ms)
3: [ RUN      ] ContentType/MasterAPITest.GetTasksNoRunningTask/1
3: I0918 09:48:28.426756 16716 cluster.cpp:162] Creating default 'local' authorizer
3: I0918 09:48:28.431480 16720 master.cpp:445] Master e8dcce0a-4670-439c-8168-75c2201f76ab (cec572d5f5ff) started on 172.17.0.2:34977
3: I0918 09:48:28.431634 16720 master.cpp:447] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/1JCObo/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/1JCObo/master" --zk_session_timeout="10secs"
3: I0918 09:48:28.432308 16720 master.cpp:497] Master only allowing authenticated frameworks to register
3: I0918 09:48:28.432394 16720 master.cpp:511] Master only allowing authenticated agents to register
3: I0918 09:48:28.432445 16720 master.cpp:524] Master only allowing authenticated HTTP frameworks to register
3: I0918 09:48:28.432513 16720 credentials.hpp:37] Loading credentials for authentication from '/tmp/1JCObo/credentials'
3: I0918 09:48:28.433037 16720 master.cpp:569] Using default 'crammd5' authenticator
3: I0918 09:48:28.433395 16720 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I0918 09:48:28.433745 16720 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I0918 09:48:28.434038 16720 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I0918 09:48:28.434303 16720 master.cpp:649] Authorization enabled
3: I0918 09:48:28.434756 16723 whitelist_watcher.cpp:77] No whitelist given
3: I0918 09:48:28.434774 16719 hierarchical.cpp:171] Initialized hierarchical allocator process
3: I0918 09:48:28.438887 16724 master.cpp:2166] Elected as the leading master!
3: I0918 09:48:28.438927 16724 master.cpp:1705] Recovering from registrar
3: I0918 09:48:28.439177 16723 registrar.cpp:347] Recovering registrar
3: I0918 09:48:28.440228 16723 registrar.cpp:391] Successfully fetched the registry (0B) in 997888ns
3: I0918 09:48:28.440780 16723 registrar.cpp:495] Applied 1 operations in 85795ns; attempting to update the registry
3: I0918 09:48:28.442042 16723 registrar.cpp:552] Successfully updated the registry in 1.150976ms
3: I0918 09:48:28.442628 16723 registrar.cpp:424] Successfully recovered registrar
3: I0918 09:48:28.443820 16722 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
3: I0918 09:48:28.443783 16724 master.cpp:1804] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I0918 09:48:28.448262 16722 process.cpp:3929] Handling HTTP event for process 'master' with path: '/master/api/v1'
3: I0918 09:48:28.451272 16718 http.cpp:1185] HTTP POST for /master/api/v1 from 172.17.0.2:49796
3: I0918 09:48:28.451488 16718 http.cpp:673] Processing call GET_TASKS
3: I0918 09:48:28.454974 16722 master.cpp:1163] Master terminating
3: [       OK ] ContentType/MasterAPITest.GetTasksNoRunningTask/1 (36 ms)
3: [ RUN      ] ContentType/MasterAPITest.GetTasks/0
3: I0918 09:48:28.463186 16716 cluster.cpp:162] Creating default 'local' authorizer
3: I0918 09:48:28.467911 16722 master.cpp:445] Master f727b0fb-328d-4a02-9258-1c8232214e51 (cec572d5f5ff) started on 172.17.0.2:34977
3: I0918 09:48:28.467960 16722 master.cpp:447] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/9dhCXR/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/9dhCXR/master" --zk_session_timeout="10secs"
3: I0918 09:48:28.469090 16722 master.cpp:497] Master only allowing authenticated frameworks to register
3: I0918 09:48:28.469105 16722 master.cpp:511] Master only allowing authenticated agents to register
3: I0918 09:48:28.469115 16722 master.cpp:524] Master only allowing authenticated HTTP frameworks to register
3: I0918 09:48:28.469125 16722 credentials.hpp:37] Loading credentials for authentication from '/tmp/9dhCXR/credentials'
3: I0918 09:48:28.469573 16722 master.cpp:569] Using default 'crammd5' authenticator
3: I0918 09:48:28.469801 16722 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I0918 09:48:28.470007 16722 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I0918 09:48:28.470121 16722 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I0918 09:48:28.470226 16722 master.cpp:649] Authorization enabled
3: I0918 09:48:28.470445 16723 whitelist_watcher.cpp:77] No whitelist given
3: I0918 09:48:28.473336 16720 hierarchical.cpp:171] Initialized hierarchical allocator process
3: I0918 09:48:28.474246 16720 master.cpp:2166] Elected as the leading master!
3: I0918 09:48:28.474357 16720 master.cpp:1705] Recovering from registrar
3: I0918 09:48:28.475338 16721 registrar.cpp:347] Recovering registrar
3: I0918 09:48:28.476374 16721 registrar.cpp:391] Successfully fetched the registry (0B) in 983040ns
3: I0918 09:48:28.476513 16721 registrar.cpp:495] Applied 1 operations in 47630ns; attempting to update the registry
3: I0918 09:48:28.477447 16721 registrar.cpp:552] Successfully updated the registry in 864us
3: I0918 09:48:28.477602 16721 registrar.cpp:424] Successfully recovered registrar
3: I0918 09:48:28.478189 16721 master.cpp:1804] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I0918 09:48:28.478514 16721 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
3: W0918 09:48:28.487864 16716 process.cpp:3194] Attempted to spawn already running process files@172.17.0.2:34977
3: I0918 09:48:28.488447 16716 cluster.cpp:448] Creating default 'local' authorizer
3: I0918 09:48:28.491510 16720 slave.cpp:252] Mesos agent started on (617)@172.17.0.2:34977
3: W0918 09:48:28.492480 16716 process.cpp:3194] Attempted to spawn already running process version@172.17.0.2:34977
3: I0918 09:48:28.491657 16720 slave.cpp:253] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/ContentType_MasterAPITest_GetTasks_0_Bo13CP/store/appc" --authenticate_http_executors="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/ContentType_MasterAPITest_GetTasks_0_Bo13CP/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/ContentType_MasterAPITest_GetTasks_0_Bo13CP/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_secret_key="/tmp/ContentType_MasterAPITest_GetTasks_0_Bo13CP/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ContentType_MasterAPITest_GetTasks_0_Bo13CP/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/ContentType_MasterAPITest_GetTasks_0_Bo13CP/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/ContentType_MasterAPITest_GetTasks_0_Bo13CP" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/ContentType_MasterAPITest_GetTasks_0_TRskNl" --zk_session_timeout="10secs"
3: I0918 09:48:28.492692 16720 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_MasterAPITest_GetTasks_0_Bo13CP/credential'
3: I0918 09:48:28.493041 16720 slave.cpp:285] Agent using credential for: test-principal
3: I0918 09:48:28.493139 16720 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_MasterAPITest_GetTasks_0_Bo13CP/http_credentials'
3: I0918 09:48:28.493700 16720 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
3: I0918 09:48:28.493991 16716 sched.cpp:232] Version: 1.5.0
3: I0918 09:48:28.494102 16720 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
3: I0918 09:48:28.494506 16720 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I0918 09:48:28.494761 16720 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
3: I0918 09:48:28.495139 16720 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I0918 09:48:28.495455 16720 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I0918 09:48:28.495995 16718 sched.cpp:336] New master detected at master@172.17.0.2:34977
3: I0918 09:48:28.496155 16718 sched.cpp:407] Authenticating with master master@172.17.0.2:34977
3: I0918 09:48:28.496175 16718 sched.cpp:414] Using default CRAM-MD5 authenticatee
3: I0918 09:48:28.496692 16717 authenticatee.cpp:121] Creating new client SASL connection
3: I0918 09:48:28.497350 16723 master.cpp:7895] Authenticating scheduler-23623c62-c384-4618-bba0-fe0ed1d85a61@172.17.0.2:34977
3: I0918 09:48:28.497992 16723 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1231)@172.17.0.2:34977
3: I0918 09:48:28.498394 16720 slave.cpp:583] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I0918 09:48:28.498792 16722 authenticator.cpp:98] Creating new server SASL connection
3: I0918 09:48:28.498894 16720 slave.cpp:591] Agent attributes: [  ]
3: I0918 09:48:28.498982 16720 slave.cpp:600] Agent hostname: cec572d5f5ff
3: I0918 09:48:28.499399 16719 status_update_manager.cpp:177] Pausing sending status updates
3: I0918 09:48:28.499433 16722 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I0918 09:48:28.499461 16722 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I0918 09:48:28.499605 16722 authenticator.cpp:204] Received SASL authentication start
3: I0918 09:48:28.499662 16722 authenticator.cpp:326] Authentication requires more steps
3: I0918 09:48:28.499758 16722 authenticatee.cpp:259] Received SASL authentication step
3: I0918 09:48:28.499871 16722 authenticator.cpp:232] Received SASL authentication step
3: I0918 09:48:28.499913 16722 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'cec572d5f5ff' server FQDN: 'cec572d5f5ff' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I0918 09:48:28.499933 16722 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I0918 09:48:28.499981 16722 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I0918 09:48:28.500011 16722 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'cec572d5f5ff' server FQDN: 'cec572d5f5ff' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I0918 09:48:28.500030 16722 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I0918 09:48:28.500041 16722 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I0918 09:48:28.500063 16722 authenticator.cpp:318] Authentication success
3: I0918 09:48:28.500326 16719 authenticatee.cpp:299] Authentication success
3: I0918 09:48:28.500391 16717 master.cpp:7925] Successfully authenticated principal 'test-principal' at scheduler-23623c62-c384-4618-bba0-fe0ed1d85a61@172.17.0.2:34977
3: I0918 09:48:28.500443 16719 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1231)@172.17.0.2:34977
3: *** Aborted at 1505728108 (unix time) try "date -d @1505728108" if you are using GNU date ***
3: I0918 09:48:28.501276 16719 sched.cpp:513] Successfully authenticated with master master@172.17.0.2:34977
3: I0918 09:48:28.501305 16719 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.2:34977
3: I0918 09:48:28.501477 16719 sched.cpp:869] Will retry registration in 348.099289ms if necessary
3: I0918 09:48:28.501885 16719 master.cpp:2897] Received SUBSCRIBE call for framework 'default' at scheduler-23623c62-c384-4618-bba0-fe0ed1d85a61@172.17.0.2:34977
3: I0918 09:48:28.501916 16719 master.cpp:2231] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
3: I0918 09:48:28.502288 16718 state.cpp:64] Recovering state from '/tmp/ContentType_MasterAPITest_GetTasks_0_TRskNl/meta'
3: I0918 09:48:28.502678 16717 status_update_manager.cpp:203] Recovering status update manager
3: I0918 09:48:28.503312 16724 slave.cpp:6298] Finished recovery
3: I0918 09:48:28.503878 16717 master.cpp:2977] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I0918 09:48:28.504667 16724 slave.cpp:6480] Querying resource estimator for oversubscribable resources
3: PC: @     0x2b1fe568b520 process::EventQueue::Consumer::empty()
3: *** SIGSEGV (@0x8) received by PID 16716 (TID 0x2b1ff23d8700) from PID 8; stack trace: ***
3: I0918 09:48:28.505357 16718 sched.cpp:759] Framework registered with f727b0fb-328d-4a02-9258-1c8232214e51-0000
3: I0918 09:48:28.505417 16718 sched.cpp:773] Scheduler::registered took 34637ns
3:     @     0x2b1fe636f330 (unknown)
3: I0918 09:48:28.505545 16720 hierarchical.cpp:303] Added framework f727b0fb-328d-4a02-9258-1c8232214e51-0000
3: I0918 09:48:28.505913 16720 hierarchical.cpp:1943] No allocations performed
3: I0918 09:48:28.506048 16720 hierarchical.cpp:2033] No inverse offers to send out!
3: I0918 09:48:28.506155 16720 hierarchical.cpp:1486] Performed allocation for 0 agents in 327753ns
3: I0918 09:48:28.506605 16717 status_update_manager.cpp:177] Pausing sending status updates
3: I0918 09:48:28.506628 16724 slave.cpp:989] New master detected at master@172.17.0.2:34977
3: I0918 09:48:28.506875 16724 slave.cpp:1024] Detecting new master
3: I0918 09:48:28.507452 16724 slave.cpp:6494] Received oversubscribable resources {} from the resource estimator
3:     @     0x2b1fe568b520 process::EventQueue::Consumer::empty()
3:     @     0x2b1fe566cd39 process::ProcessManager::resume()
3:     @     0x2b1fe567cd31 process::ProcessManager::init_threads()::$_9::operator()()
3:     @     0x2b1fe567cc75 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvE3$_9vEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE
3:     @     0x2b1fe567cc45 std::_Bind_simple<>::operator()()
3:     @     0x2b1fe567cc1c std::thread::_Impl<>::_M_run()
3:     @     0x2b1fe9dc0a60 (unknown)
3:     @     0x2b1fe6367184 start_thread
3:     @     0x2b1fea62cffd (unknown)
3/3 Test #3: MesosTests .......................***Exception: SegFault379.17 sec

67% tests passed, 1 tests failed out of 3

Total Test time (real) = 449.71 sec

The following tests FAILED:
	  3 - MesosTests (SEGFAULT)
Errors while running CTest
make[3]: Leaving directory `/mesos/build'
make[3]: *** [CMakeFiles/check] Error 8
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[2]: Leaving directory `/mesos/build'
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1505725006-19757
Untagged: mesos-1505725006-19757:latest
Deleted: sha256:603f05ecc68dbee35041e0591afc9154b35246800a6e46fa0a83e83090c23125
Deleted: sha256:87680cfb74a62161f0afaa187967572b551980667bb0f5e3b460230ef0ab1280
Deleted: sha256:6caa83c1e628f62c45c5dd3bea75e7dcacb8131eb38d85c6ae06a7c437a5d53d
Deleted: sha256:d794387505175ba605cf10ca7b92478968011f063b7c83b9f28d9b91895ac087
Deleted: sha256:e56bc649a9c1dcb8ac4278c7bded68ed21d7d11f7a73fd7d575699b31a94bf5c
Deleted: sha256:0581af091e53ca08044f0a3be4fc6ee93a2b32bca7bf925bb0107bdfa34e880c
Deleted: sha256:6f0b0dca22b5491f68066c9a83a0810ce4a969843eb4f763eb73144a0107dc97
Deleted: sha256:8a5f5624418b468533a0e87f29191267ab7e3d52e4dfebc914b1e3eb2ed610e9
Deleted: sha256:1674d82e05e469601e6b3cf6006aad29c66b7cd596651f457539d27b83af3592
Deleted: sha256:785d0924ae73362ba93d4b89d5450b44b7a3209dcb28c7394a58952591528b6f
Deleted: sha256:b413ee86c7de130686952c7e7609ca793ef2de4940eeea349bf0aca0d38e75d2
Deleted: sha256:d4168ed23056df532f21a892b30a45674e4757d8a02f36c17e0085bcc79646d6
Deleted: sha256:e4adb9bdd422e894c696961c57d87132b152d62ff151d93d53e822150331e62e
Deleted: sha256:0f36be78ac841d0ea53c8fac29a8b8bd8302ddc0f9b783be99fa3ad59294ad58
Deleted: sha256:b9e018be038af963979414ee7e5c3262af3fffc25e120adacda29ce86315bf18
Deleted: sha256:ad57c5de300ff8f625c3e5152d156c719642c7f46b2eb13df95562136c27e630
Deleted: sha256:1ce27b3226de91eafcb58c0275895148beb5c81033fe084828174430f66a9ca5
Deleted: sha256:92d0cd7b1c577bdced41d0affa7d65ac067d4f59443b63934d52875316a612d3
Deleted: sha256:6872de6b778813f5ffc6c1f150846a01547440999d44b1c152616b37cc480881
Deleted: sha256:dcb19c4ca6cc0300de67df760b5b82b9674f24f8923d171b7afa7af13f86a512
Deleted: sha256:66b3de87b5ca9603384c8c1798c58a0b11e355210ab40e81246c90c77744bfd6
Deleted: sha256:024505270c701a2295c3a115942b5fd75efc567a85ed5a4818f10a27aa42e7cd
Deleted: sha256:32858499d34e4b53c73542bc026785a349aebbd33b650e468d25db3077be62fb
Deleted: sha256:d38f4179e626f18510ca778d38604afa49b1838f4fd737640d17bac8619b79df
Deleted: sha256:7fd5ca8d9c26a61e36cdb0d704c831b834d17e2e267982cf7f025812a2d61761
Deleted: sha256:07b08f632b88adcdea7adfc8660484cbea42561bef46fa113ba0e942ec11dab9
Deleted: sha256:84649879225ab5abbaf22c1e99ae40d5f12b9e06a2095d46fc69e03adf51ba37
Deleted: sha256:ae8b2c7fbc6dfe3b1b8db14d6d3175b5d6af2aee1d2ec45e267289043887b0c5
Build step 'Execute shell' marked build as failure

Jenkins build is back to normal : Mesos-Buildbot » cmake,clang,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4236

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4236/display/redirect?page=changes>