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/03/09 18:23:14 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3352
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3352/display/redirect?page=changes>
Changes:
[neil.conway] Minor cleanup for slave tests.
[neil.conway] Cleaned up sorter test cases.
[neil.conway] Cleaned up header includes.
[neil.conway] Minor cleanup for quota validation code.
[neil.conway] Simplified interface for setting weights in allocator.
------------------------------------------
[...truncated 11.90 MB...]
I0309 18:21:52.199051 25371 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 038de131-6b92-4784-abf0-8ddc160fdfa2) for task 1 of framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000
I0309 18:21:52.199126 25364 exec.cpp:396] Executor received framework message
I0309 18:21:52.199185 25364 exec.cpp:405] Executor::frameworkMessage took 37474ns
I0309 18:21:52.199193 25365 slave.cpp:3106] Status update manager successfully handled status update acknowledgement (UUID: 038de131-6b92-4784-abf0-8ddc160fdfa2) for task 1 of framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000
I0309 18:21:52.199492 25377 slave.cpp:4325] Sending message for framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000 to scheduler-225fef22-3244-4f94-b032-6ca29e97fe11@172.17.0.2:47986
I0309 18:21:52.199637 25365 sched.cpp:1177] Scheduler::frameworkMessage took 29206ns
I0309 18:21:52.199805 25363 sched.cpp:2021] Asked to stop the driver
I0309 18:21:52.199862 25373 sched.cpp:1203] Stopping framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000
I0309 18:21:52.199970 25369 master.cpp:7765] Processing TEARDOWN call for framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000 (default) at scheduler-225fef22-3244-4f94-b032-6ca29e97fe11@172.17.0.2:47986
I0309 18:21:52.200000 25369 master.cpp:7777] Removing framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000 (default) at scheduler-225fef22-3244-4f94-b032-6ca29e97fe11@172.17.0.2:47986
I0309 18:21:52.200021 25369 master.cpp:3148] Deactivating framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000 (default) at scheduler-225fef22-3244-4f94-b032-6ca29e97fe11@172.17.0.2:47986
I0309 18:21:52.200091 25369 master.cpp:8345] Updating the state of task 1 of framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0309 18:21:52.200098 25378 hierarchical.cpp:376] Deactivated framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000
I0309 18:21:52.200121 25374 slave.cpp:2664] Asked to shut down framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000 by master@172.17.0.2:47986
I0309 18:21:52.200150 25374 slave.cpp:2689] Shutting down framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000
I0309 18:21:52.200177 25374 slave.cpp:5084] Shutting down executor 'default' of framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000 at executor(120)@172.17.0.2:47986
I0309 18:21:52.200275 25368 exec.cpp:415] Executor asked to shutdown
I0309 18:21:52.200302 25368 exec.cpp:430] Executor::shutdown took 10760ns
I0309 18:21:52.200335 25369 master.cpp:8439] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000 on agent c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-S0 at slave(286)@172.17.0.2:47986 (6223d2d9c6c8)
I0309 18:21:52.200417 25373 slave.cpp:4389] Got exited event for executor(120)@172.17.0.2:47986
I0309 18:21:52.200510 25369 master.cpp:8468] Removing executor 'default' with resources {} of framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000 on agent c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-S0 at slave(286)@172.17.0.2:47986 (6223d2d9c6c8)
I0309 18:21:52.200489 25379 hierarchical.cpp:1108] 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 c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-S0 from framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000
I0309 18:21:52.200964 25379 hierarchical.cpp:323] Removed framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000
I0309 18:21:52.201071 25368 slave.cpp:4770] Executor 'default' of framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000 exited with status 0
I0309 18:21:52.201131 25368 slave.cpp:4870] Cleaning up executor 'default' of framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000 at executor(120)@172.17.0.2:47986
W0309 18:21:52.201164 25374 master.cpp:6367] Ignoring unknown exited executor 'default' of framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000 on agent c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-S0 at slave(286)@172.17.0.2:47986 (6223d2d9c6c8)
I0309 18:21:52.201387 25378 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_gM1Plx/slaves/c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-S0/frameworks/c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000/executors/default/runs/4a8c34b4-d3f0-4196-93f0-15eea32ee7f6' for gc 6.9999976701037days in the future
I0309 18:21:52.201434 25368 slave.cpp:4958] Cleaning up framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000
I0309 18:21:52.201453 25378 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_gM1Plx/slaves/c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-S0/frameworks/c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000/executors/default' for gc 6.99999766880593days in the future
I0309 18:21:52.201560 25374 status_update_manager.cpp:285] Closing status update streams for framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000
I0309 18:21:52.201584 25374 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000
I0309 18:21:52.201634 25368 slave.cpp:808] Agent terminating
I0309 18:21:52.201627 25366 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_gM1Plx/slaves/c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-S0/frameworks/c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-0000' for gc 6.99999766693333days in the future
I0309 18:21:52.201732 25369 master.cpp:1317] Agent c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-S0 at slave(286)@172.17.0.2:47986 (6223d2d9c6c8) disconnected
I0309 18:21:52.201759 25369 master.cpp:3185] Disconnecting agent c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-S0 at slave(286)@172.17.0.2:47986 (6223d2d9c6c8)
I0309 18:21:52.201797 25369 master.cpp:3204] Deactivating agent c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-S0 at slave(286)@172.17.0.2:47986 (6223d2d9c6c8)
I0309 18:21:52.201892 25376 hierarchical.cpp:655] Agent c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-S0 deactivated
I0309 18:21:52.203107 25363 master.cpp:1156] Master terminating
I0309 18:21:52.203369 25367 hierarchical.cpp:560] Removed agent c1d84ef6-49a0-4bf4-bf57-e3cfaede90a2-S0
I0309 18:21:52.207185 25363 cluster.cpp:160] Creating default 'local' authorizer
I0309 18:21:52.208070 25364 master.cpp:437] Master 7aa18b24-e21e-4535-8d54-59e966748ede (6223d2d9c6c8) started on 172.17.0.2:47986
I0309 18:21:52.208092 25364 master.cpp:439] 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/WbDTZY/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --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/WbDTZY/master" --zk_session_timeout="10secs"
I0309 18:21:52.208297 25364 master.cpp:489] Master only allowing authenticated frameworks to register
I0309 18:21:52.208312 25364 master.cpp:503] Master only allowing authenticated agents to register
I0309 18:21:52.208323 25364 master.cpp:516] Master only allowing authenticated HTTP frameworks to register
I0309 18:21:52.208335 25364 credentials.hpp:37] Loading credentials for authentication from '/tmp/WbDTZY/credentials'
I0309 18:21:52.208464 25364 master.cpp:561] Using default 'crammd5' authenticator
I0309 18:21:52.208519 25364 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0309 18:21:52.208590 25364 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0309 18:21:52.208638 25364 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0309 18:21:52.208686 25364 master.cpp:641] Authorization enabled
I0309 18:21:52.208746 25376 whitelist_watcher.cpp:77] No whitelist given
I0309 18:21:52.208829 25368 hierarchical.cpp:159] Initialized hierarchical allocator process
I0309 18:21:52.209445 25371 master.cpp:2165] Elected as the leading master!
I0309 18:21:52.209463 25371 master.cpp:1704] Recovering from registrar
I0309 18:21:52.209527 25368 registrar.cpp:331] Recovering registrar
I0309 18:21:52.209800 25368 registrar.cpp:364] Successfully fetched the registry (0B) in 248064ns
I0309 18:21:52.209837 25368 registrar.cpp:463] Applied 1 operations in 2778ns; attempting to update the registry
I0309 18:21:52.210063 25368 registrar.cpp:508] Successfully updated the registry in 197888ns
I0309 18:21:52.210132 25368 registrar.cpp:394] Successfully recovered registrar
I0309 18:21:52.210304 25375 master.cpp:1803] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0309 18:21:52.210337 25366 hierarchical.cpp:186] Skipping recovery of hierarchical allocator: nothing to recover
I0309 18:21:52.212474 25363 cluster.cpp:446] Creating default 'local' authorizer
I0309 18:21:52.212981 25369 slave.cpp:215] Mesos agent started on (287)@172.17.0.2:47986
I0309 18:21:52.213001 25369 slave.cpp:216] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --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/MasterTest_MultipleExecutors_qW0vWc/credential" --default_role="*" --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/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_qW0vWc/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/MasterTest_MultipleExecutors_qW0vWc/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" --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/MasterTest_MultipleExecutors_qW0vWc" --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/MasterTest_MultipleExecutors_cgjcY5"
I0309 18:21:52.213371 25369 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_qW0vWc/credential'
I0309 18:21:52.213472 25369 slave.cpp:358] Agent using credential for: test-principal
I0309 18:21:52.213490 25369 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_qW0vWc/http_credentials'
I0309 18:21:52.213629 25369 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0309 18:21:52.213636 25363 sched.cpp:232] Version: 1.3.0
I0309 18:21:52.213709 25369 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0309 18:21:52.213800 25364 sched.cpp:336] New master detected at master@172.17.0.2:47986
I0309 18:21:52.213846 25364 sched.cpp:407] Authenticating with master master@172.17.0.2:47986
I0309 18:21:52.213866 25364 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0309 18:21:52.213961 25376 authenticatee.cpp:121] Creating new client SASL connection
I0309 18:21:52.214145 25365 master.cpp:7252] Authenticating scheduler-989c66be-f46c-4633-9d0f-6ede3ea2a8d8@172.17.0.2:47986
I0309 18:21:52.214210 25364 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(633)@172.17.0.2:47986
I0309 18:21:52.214285 25374 authenticator.cpp:98] Creating new server SASL connection
I0309 18:21:52.214285 25369 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0309 18:21:52.214337 25369 slave.cpp:553] Agent attributes: [ ]
I0309 18:21:52.214346 25369 slave.cpp:558] Agent hostname: 6223d2d9c6c8
I0309 18:21:52.214413 25368 status_update_manager.cpp:177] Pausing sending status updates
I0309 18:21:52.214445 25376 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0309 18:21:52.214493 25376 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0309 18:21:52.214619 25364 authenticator.cpp:204] Received SASL authentication start
I0309 18:21:52.214682 25364 authenticator.cpp:326] Authentication requires more steps
I0309 18:21:52.214751 25376 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_cgjcY5/meta'
I0309 18:21:52.214803 25377 authenticatee.cpp:259] Received SASL authentication step
I0309 18:21:52.215045 25377 authenticator.cpp:232] Received SASL authentication step
I0309 18:21:52.215081 25377 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '6223d2d9c6c8' server FQDN: '6223d2d9c6c8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0309 18:21:52.215090 25370 status_update_manager.cpp:203] Recovering status update manager
I0309 18:21:52.215136 25377 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0309 18:21:52.215169 25377 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0309 18:21:52.215307 25377 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '6223d2d9c6c8' server FQDN: '6223d2d9c6c8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0309 18:21:52.215384 25377 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0309 18:21:52.215426 25377 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0309 18:21:52.215430 25371 slave.cpp:5572] Finished recovery
I0309 18:21:52.215474 25377 authenticator.cpp:318] Authentication success
I0309 18:21:52.215621 25376 authenticatee.cpp:299] Authentication success
I0309 18:21:52.215653 25379 master.cpp:7282] Successfully authenticated principal 'test-principal' at scheduler-989c66be-f46c-4633-9d0f-6ede3ea2a8d8@172.17.0.2:47986
I0309 18:21:52.215678 25368 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(633)@172.17.0.2:47986
I0309 18:21:52.215845 25371 slave.cpp:5754] Querying resource estimator for oversubscribable resources
I0309 18:21:52.215889 25376 sched.cpp:513] Successfully authenticated with master master@172.17.0.2:47986
I0309 18:21:52.215944 25376 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.2:47986
I0309 18:21:52.216012 25370 slave.cpp:936] New master detected at master@172.17.0.2:47986
I0309 18:21:52.216020 25374 status_update_manager.cpp:177] Pausing sending status updates
I0309 18:21:52.216058 25370 slave.cpp:971] Detecting new master
I0309 18:21:52.216060 25376 sched.cpp:869] Will retry registration in 1.185922878secs if necessary
I0309 18:21:52.216130 25370 slave.cpp:5768] Received oversubscribable resources {} from the resource estimator
I0309 18:21:52.216181 25372 master.cpp:2817] Received SUBSCRIBE call for framework 'default' at scheduler-989c66be-f46c-4633-9d0f-6ede3ea2a8d8@172.17.0.2:47986
I0309 18:21:52.216214 25372 master.cpp:2201] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0309 18:21:52.216348 25377 master.cpp:2894] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0309 18:21:52.216564 25376 hierarchical.cpp:271] Added framework 7aa18b24-e21e-4535-8d54-59e966748ede-0000
I0309 18:21:52.216572 25374 sched.cpp:759] Framework registered with 7aa18b24-e21e-4535-8d54-59e966748ede-0000
I0309 18:21:52.216609 25374 sched.cpp:773] Scheduler::registered took 12019ns
I0309 18:21:52.216634 25376 hierarchical.cpp:1854] No allocations performed
I0309 18:21:52.216648 25376 hierarchical.cpp:1944] No inverse offers to send out!
I0309 18:21:52.216660 25376 hierarchical.cpp:1438] Performed allocation for 0 agents in 35369ns
I0309 18:21:52.223325 25377 slave.cpp:998] Authenticating with master master@172.17.0.2:47986
I0309 18:21:52.223356 25377 slave.cpp:1009] Using default CRAM-MD5 authenticatee
I0309 18:21:52.223424 25365 authenticatee.cpp:121] Creating new client SASL connection
I0309 18:21:52.223585 25372 master.cpp:7252] Authenticating slave(287)@172.17.0.2:47986
I0309 18:21:52.223651 25365 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(634)@172.17.0.2:47986
I0309 18:21:52.223739 25377 authenticator.cpp:98] Creating new server SASL connection
I0309 18:21:52.223871 25377 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0309 18:21:52.223896 25377 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0309 18:21:52.223944 25377 authenticator.cpp:204] Received SASL authentication start
I0309 18:21:52.223994 25377 authenticator.cpp:326] Authentication requires more steps
I0309 18:21:52.224061 25377 authenticatee.cpp:259] Received SASL authentication step
I0309 18:21:52.224138 25375 authenticator.cpp:232] Received SASL authentication step
I0309 18:21:52.224169 25375 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '6223d2d9c6c8' server FQDN: '6223d2d9c6c8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0309 18:21:52.224184 25375 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0309 18:21:52.224195 25375 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0309 18:21:52.224207 25375 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '6223d2d9c6c8' server FQDN: '6223d2d9c6c8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0309 18:21:52.224216 25375 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0309 18:21:52.224226 25375 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0309 18:21:52.224241 25375 authenticator.cpp:318] Authentication success
I0309 18:21:52.224298 25367 authenticatee.cpp:299] Authentication success
I0309 18:21:52.224318 25375 master.cpp:7282] Successfully authenticated principal 'test-principal' at slave(287)@172.17.0.2:47986
I0309 18:21:52.224352 25372 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(634)@172.17.0.2:47986
I0309 18:21:52.224453 25367 slave.cpp:1093] Successfully authenticated with master master@172.17.0.2:47986
I0309 18:21:52.224517 25367 slave.cpp:1521] Will retry registration in 11.93529ms if necessary
I0309 18:21:52.224588 25370 master.cpp:5437] Registering agent at slave(287)@172.17.0.2:47986 (6223d2d9c6c8) with id 7aa18b24-e21e-4535-8d54-59e966748ede-S0
I0309 18:21:52.224727 25373 registrar.cpp:463] Applied 1 operations in 11732ns; attempting to update the registry
I0309 18:21:52.225126 25376 registrar.cpp:508] Successfully updated the registry in 373248ns
I0309 18:21:52.225392 25376 slave.cpp:4347] Received ping from slave-observer(280)@172.17.0.2:47986
I0309 18:21:52.225412 25369 master.cpp:5511] Registered agent 7aa18b24-e21e-4535-8d54-59e966748ede-S0 at slave(287)@172.17.0.2:47986 (6223d2d9c6c8) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0309 18:21:52.225473 25376 slave.cpp:1139] Registered with master master@172.17.0.2:47986; given agent ID 7aa18b24-e21e-4535-8d54-59e966748ede-S0
I0309 18:21:52.225528 25375 hierarchical.cpp:527] Added agent 7aa18b24-e21e-4535-8d54-59e966748ede-S0 (6223d2d9c6c8) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0309 18:21:52.225606 25376 fetcher.cpp:94] Clearing fetcher cache
I0309 18:21:52.225728 25367 status_update_manager.cpp:184] Resuming sending status updates
I0309 18:21:52.225932 25375 hierarchical.cpp:1944] No inverse offers to send out!
I0309 18:21:52.225955 25375 hierarchical.cpp:1438] Performed allocation for 1 agents in 294352ns
I0309 18:21:52.225998 25376 slave.cpp:1167] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_cgjcY5/meta/slaves/7aa18b24-e21e-4535-8d54-59e966748ede-S0/slave.info'
I0309 18:21:52.226187 25377 master.cpp:7081] Sending 1 offers to framework 7aa18b24-e21e-4535-8d54-59e966748ede-0000 (default) at scheduler-989c66be-f46c-4633-9d0f-6ede3ea2a8d8@172.17.0.2:47986
I0309 18:21:52.226346 25376 slave.cpp:1205] Forwarding total oversubscribed resources {}
I0309 18:21:52.226436 25376 master.cpp:6098] Received update of agent 7aa18b24-e21e-4535-8d54-59e966748ede-S0 at slave(287)@172.17.0.2:47986 (6223d2d9c6c8) with total oversubscribed resources {}
I0309 18:21:52.226447 25372 sched.cpp:933] Scheduler::resourceOffers took 67512ns
I0309 18:21:52.227335 25365 master.cpp:3839] Processing ACCEPT call for offers: [ 7aa18b24-e21e-4535-8d54-59e966748ede-O0 ] on agent 7aa18b24-e21e-4535-8d54-59e966748ede-S0 at slave(287)@172.17.0.2:47986 (6223d2d9c6c8) for framework 7aa18b24-e21e-4535-8d54-59e966748ede-0000 (default) at scheduler-989c66be-f46c-4633-9d0f-6ede3ea2a8d8@172.17.0.2:47986
I0309 18:21:52.227397 25365 master.cpp:3415] Authorizing framework principal 'test-principal' to launch task 1
I0309 18:21:52.227553 25365 master.cpp:3415] Authorizing framework principal 'test-principal' to launch task 2
W0309 18:21:52.228222 25372 validation.cpp:1113] Executor 'executor-1' for task '1' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0309 18:21:52.228248 25372 validation.cpp:1125] Executor 'executor-1' for task '1' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0309 18:21:52.228382 25372 master.cpp:9097] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 7aa18b24-e21e-4535-8d54-59e966748ede-S0 at slave(287)@172.17.0.2:47986 (6223d2d9c6c8)
I0309 18:21:52.228468 25372 master.cpp:4489] Launching task 1 of framework 7aa18b24-e21e-4535-8d54-59e966748ede-0000 (default) at scheduler-989c66be-f46c-4633-9d0f-6ede3ea2a8d8@172.17.0.2:47986 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 7aa18b24-e21e-4535-8d54-59e966748ede-S0 at slave(287)@172.17.0.2:47986 (6223d2d9c6c8)
I0309 18:21:52.228662 25364 slave.cpp:1631] Got assigned task '1' for framework 7aa18b24-e21e-4535-8d54-59e966748ede-0000
W0309 18:21:52.228860 25372 validation.cpp:1113] Executor 'executor-2' for task '2' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0309 18:21:52.228886 25372 validation.cpp:1125] Executor 'executor-2' for task '2' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0309 18:21:52.228952 25364 slave.cpp:1791] Launching task '1' for framework 7aa18b24-e21e-4535-8d54-59e966748ede-0000
I0309 18:21:52.229014 25372 master.cpp:9097] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 7aa18b24-e21e-4535-8d54-59e966748ede-S0 at slave(287)@172.17.0.2:47986 (6223d2d9c6c8)
I0309 18:21:52.229079 25372 master.cpp:4489] Launching task 2 of framework 7aa18b24-e21e-4535-8d54-59e966748ede-0000 (default) at scheduler-989c66be-f46c-4633-9d0f-6ede3ea2a8d8@172.17.0.2:47986 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 7aa18b24-e21e-4535-8d54-59e966748ede-S0 at slave(287)@172.17.0.2:47986 (6223d2d9c6c8)
I0309 18:21:52.229676 25364 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_cgjcY5/slaves/7aa18b24-e21e-4535-8d54-59e966748ede-S0/frameworks/7aa18b24-e21e-4535-8d54-59e966748ede-0000/executors/executor-1/runs/d597ab53-3483-427e-840b-8a7f0e1203c0' to user 'mesos'
I0309 18:21:52.229917 25364 slave.cpp:6499] Launching executor 'executor-1' of framework 7aa18b24-e21e-4535-8d54-59e966748ede-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_cgjcY5/slaves/7aa18b24-e21e-4535-8d54-59e966748ede-S0/frameworks/7aa18b24-e21e-4535-8d54-59e966748ede-0000/executors/executor-1/runs/d597ab53-3483-427e-840b-8a7f0e1203c0'
I0309 18:21:52.230082 25371 hierarchical.cpp:844] Updated allocation of framework 7aa18b24-e21e-4535-8d54-59e966748ede-0000 on agent 7aa18b24-e21e-4535-8d54-59e966748ede-S0 from cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] to cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000]
I0309 18:21:52.230193 25364 slave.cpp:2124] Queued task '1' for executor 'executor-1' of framework 7aa18b24-e21e-4535-8d54-59e966748ede-0000
I0309 18:21:52.230279 25364 slave.cpp:1631] Got assigned task '2' for framework 7aa18b24-e21e-4535-8d54-59e966748ede-0000
I0309 18:21:52.230334 25371 hierarchical.cpp:1108] Recovered ports(*)(allocated: *):[31000-32000]; disk(*)(allocated: *):1024 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024) on agent 7aa18b24-e21e-4535-8d54-59e966748ede-S0 from framework 7aa18b24-e21e-4535-8d54-59e966748ede-0000
I0309 18:21:52.230386 25371 hierarchical.cpp:1145] Framework 7aa18b24-e21e-4535-8d54-59e966748ede-0000 filtered agent 7aa18b24-e21e-4535-8d54-59e966748ede-S0 for 5secs
I0309 18:21:52.230474 25364 slave.cpp:889] Successfully attached file '/tmp/MasterTest_MultipleExecutors_cgjcY5/slaves/7aa18b24-e21e-4535-8d54-59e966748ede-S0/frameworks/7aa18b24-e21e-4535-8d54-59e966748ede-0000/executors/executor-1/runs/d597ab53-3483-427e-840b-8a7f0e1203c0'
I0309 18:21:52.230509 25364 slave.cpp:1791] Launching task '2' for framework 7aa18b24-e21e-4535-8d54-59e966748ede-0000
I0309 18:21:52.230867 25364 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_cgjcY5/slaves/7aa18b24-e21e-4535-8d54-59e966748ede-S0/frameworks/7aa18b24-e21e-4535-8d54-59e966748ede-0000/executors/executor-2/runs/19682ac0-2890-44f3-8df6-ff14085d74c8' to user 'mesos'
I0309 18:21:52.231055 25364 slave.cpp:6499] Launching executor 'executor-2' of framework 7aa18b24-e21e-4535-8d54-59e966748ede-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_cgjcY5/slaves/7aa18b24-e21e-4535-8d54-59e966748ede-S0/frameworks/7aa18b24-e21e-4535-8d54-59e966748ede-0000/executors/executor-2/runs/19682ac0-2890-44f3-8df6-ff14085d74c8'
*** Aborted at 1489083712 (unix time) try "date -d @1489083712" if you are using GNU date ***
I0309 18:21:52.231307 25365 exec.cpp:162] Version: 1.3.0
I0309 18:21:52.231361 25370 exec.cpp:212] Executor started at: executor(121)@172.17.0.2:47986 with pid 25363
PC: @ 0x2b5d2c73082d (unknown)
*** SIGSEGV (@0xc0) received by PID 25363 (TID 0x2b5d34884700) from PID 192; stack trace: ***
@ 0x2b5d2bcc7330 (unknown)
@ 0x2b5d2c73082d (unknown)
@ 0x2b5d292196a4 mesos::internal::slave::executorEnvironment()
@ 0x2b5d29245071 mesos::internal::slave::Framework::launchExecutor()
@ 0x2b5d29247fa7 mesos::internal::slave::Slave::_run()
@ 0x2b5d2a4f1df1 process::ProcessManager::resume()
@ 0x2b5d2a4fafd7 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x2b5d2bf86a60 (unknown)
@ 0x2b5d2bcbf184 start_thread
@ 0x2b5d2c7ef37d (unknown)
make[3]: *** [CMakeFiles/check] Segmentation fault
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make: *** [check] Error 2
[ OK ] MasterTest.TaskRunning (2203 ms)
[ RUN ] MasterTest.ShutdownFrameworkWhileTaskRunning
[ OK ] MasterTest.ShutdownFrameworkWhileTaskRunning (40 ms)
[ RUN ] MasterTest.KillTask
[ OK ] MasterTest.KillTask (32 ms)
[ RUN ] MasterTest.KillUnknownTask
[ OK ] MasterTest.KillUnknownTask (29 ms)
[ RUN ] MasterTest.KillUnknownTaskSlaveInTransition
[ OK ] MasterTest.KillUnknownTaskSlaveInTransition (94 ms)
[ RUN ] MasterTest.EndpointsForHalfRemovedSlave
[ OK ] MasterTest.EndpointsForHalfRemovedSlave (73 ms)
[ RUN ] MasterTest.StatusUpdateAck
[ OK ] MasterTest.StatusUpdateAck (37 ms)
[ RUN ] MasterTest.RecoverResources
[ OK ] MasterTest.RecoverResources (41 ms)
[ RUN ] MasterTest.FrameworkMessage
[ OK ] MasterTest.FrameworkMessage (35 ms)
[ RUN ] MasterTest.MultipleExecutors
make[3]: Leaving directory `/mesos/build'
make[2]: Leaving directory `/mesos/build'
make[1]: Leaving directory `/mesos/build'
+ docker rmi mesos-1489081176-2733
Untagged: mesos-1489081176-2733:latest
Deleted: sha256:703b41ee88af1f6db447c438eec62ddbc71623842853023126ffc6de461950d1
Deleted: sha256:3b0563134ade236d793abf97b86bd77c7ce5f48cb3a5b3da3801388c9a4df57a
Deleted: sha256:cd9005c1826519c6f9c2f872de840340ade95da43877d3e1b22391e7b6b22fdd
Deleted: sha256:5fd06dcc33a3cf633c14d1a598714e7d36638cad2bd19fba412d426a7014be54
Deleted: sha256:04e34c82639b90ae4a1f04bc6498b730d66a9d844e1d7edd73eee33253ce8ab7
Deleted: sha256:33812049e7128759d700e77b59ec253ad13696789f847106cafb286fe294de07
Deleted: sha256:1a44cfcccccc0f23e7aa393f17b562d84d7c23bb1b7c18f12bfdfd4e21d4f281
Deleted: sha256:4e55c02b5921bb0b3769c1c5778343c4fb220c630e4ef655440ad32421ebc512
Deleted: sha256:49afd8ed54fbbb6f0517eeea32b4b4e54cb28d491b635f437637e09c5a85b418
Deleted: sha256:a425d1017bb9eac974601ad67e15be9fd7161406001acc106aeb4790eb29e75e
Deleted: sha256:e9b791768fa33ff9e7ac0d5621f58d95f7cd2e217853e5a10046846efab59a74
Deleted: sha256:574b519366a14dc5205b8cec0de742c03bbbe9a7d248206bff304e0ac9bf7c50
Deleted: sha256:500268b7761031e01891d755a13545aa5530bbeba290e2e058c4ef9dbe99a21a
Deleted: sha256:f5519ce97213d9c80a0358cdd0713fa62e57867f76f5f8f0bbf5837711736eb5
Deleted: sha256:33012378b46d3a0e2dc5c12c2b844d9a7af17b3a3e5308065fe86af31beb0120
Deleted: sha256:6fea26a741752431f061959d5fbdb388078d8b6a900f0f7e84a3b6e8e61a5cf3
Deleted: sha256:dcd7387308c201c835ab800dca8490bad685f2b2b0a932d75412351d89045434
Deleted: sha256:9b01b23ff2096ac756f99e73758c79bac4ad1be810056508813ee9c36e9b2fa7
Deleted: sha256:9bb92a4b45d081e7ae0fbe0e53afbec781532ff7d8fafe954d34971229d6cec2
Deleted: sha256:37be8179d65d29b3cc68485a92022e0ccefabd091c3e2dad40829af28b5deaf8
Deleted: sha256:6d087c5bb667210f7592ab87d376550c0cfda5f2c02d42b721e2952e3163b10b
Deleted: sha256:1058e1df0047041d1eef18881ab0924ee66b3a10be1417f267172ff5d9c58ef4
Deleted: sha256:ba31ee4c8013520adf6241d0b2572ea5219376990f2aa33f4b3d2f88cd30eb33
Deleted: sha256:704890e45a0b85982a2f66bb1db10a0b640a400d865dffb9076c5d9b829b4a0e
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » cmake,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3353
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3353/display/redirect?page=changes>