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/11/04 00:08:45 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4402
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers%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)/4402/display/redirect?page=changes>
Changes:
[jpeach] Fixed default executor handling of nested container status.
[jpeach] Added MESOS-8080 to the 1.3.2 CHANGELOG.
------------------------------------------
[...truncated 13.01 MB...]
I1104 00:08:37.560266 26747 exec.cpp:388] Executor received status update acknowledgement b9193141-cb57-4901-9a5c-b9ec339551db for task 1 of framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000
I1104 00:08:37.560276 26743 master.cpp:6539] Forwarding status update TASK_RUNNING (UUID: b9193141-cb57-4901-9a5c-b9ec339551db) for task 1 of framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000
I1104 00:08:37.560333 26743 master.cpp:8570] Updating the state of task 1 of framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I1104 00:08:37.560425 26732 sched.cpp:1041] Scheduler::statusUpdate took 53311ns
I1104 00:08:37.560654 26735 master.cpp:5196] Processing ACKNOWLEDGE call b9193141-cb57-4901-9a5c-b9ec339551db for task 1 of framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000 (default) at scheduler-5a8a58a7-4781-4315-809d-8f94eea62591@172.17.0.4:33143 on agent 9d60c9d5-2096-461e-8478-2ccaa78943f9-S0
I1104 00:08:37.560858 26747 status_update_manager.cpp:395] Received status update acknowledgement (UUID: b9193141-cb57-4901-9a5c-b9ec339551db) for task 1 of framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000
I1104 00:08:37.561076 26745 exec.cpp:416] Executor received framework message
I1104 00:08:37.561079 26736 slave.cpp:3504] Status update manager successfully handled status update acknowledgement (UUID: b9193141-cb57-4901-9a5c-b9ec339551db) for task 1 of framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000
I1104 00:08:37.561118 26745 exec.cpp:425] Executor::frameworkMessage took 32941ns
I1104 00:08:37.561453 26745 slave.cpp:4795] Sending message for framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000 to scheduler-5a8a58a7-4781-4315-809d-8f94eea62591@172.17.0.4:33143
I1104 00:08:37.561605 26732 sched.cpp:1177] Scheduler::frameworkMessage took 100599ns
I1104 00:08:37.561857 26726 sched.cpp:2021] Asked to stop the driver
I1104 00:08:37.562031 26732 sched.cpp:1203] Stopping framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000
I1104 00:08:37.562101 26730 master.cpp:7990] Processing TEARDOWN call for framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000 (default) at scheduler-5a8a58a7-4781-4315-809d-8f94eea62591@172.17.0.4:33143
I1104 00:08:37.562119 26730 master.cpp:8002] Removing framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000 (default) at scheduler-5a8a58a7-4781-4315-809d-8f94eea62591@172.17.0.4:33143
I1104 00:08:37.562130 26730 master.cpp:3160] Deactivating framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000 (default) at scheduler-5a8a58a7-4781-4315-809d-8f94eea62591@172.17.0.4:33143
I1104 00:08:37.562230 26737 hierarchical.cpp:374] Deactivated framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000
I1104 00:08:37.562245 26730 master.cpp:8570] Updating the state of task 1 of framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I1104 00:08:37.562265 26749 slave.cpp:3057] Asked to shut down framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000 by master@172.17.0.4:33143
I1104 00:08:37.562286 26749 slave.cpp:3082] Shutting down framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000
I1104 00:08:37.562299 26749 slave.cpp:5556] Shutting down executor 'default' of framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000 at executor(121)@172.17.0.4:33143
I1104 00:08:37.562399 26737 exec.cpp:435] Executor asked to shutdown
I1104 00:08:37.562422 26737 exec.cpp:450] Executor::shutdown took 12215ns
I1104 00:08:37.562460 26732 hierarchical.cpp:1114] 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 9d60c9d5-2096-461e-8478-2ccaa78943f9-S0 from framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000
I1104 00:08:37.562486 26730 master.cpp:8664] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000 on agent 9d60c9d5-2096-461e-8478-2ccaa78943f9-S0 at slave(309)@172.17.0.4:33143 (e8c2c8e95099)
I1104 00:08:37.562563 26730 master.cpp:8693] Removing executor 'default' with resources {} of framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000 on agent 9d60c9d5-2096-461e-8478-2ccaa78943f9-S0 at slave(309)@172.17.0.4:33143 (e8c2c8e95099)
I1104 00:08:37.562633 26727 slave.cpp:4859] Got exited event for executor(121)@172.17.0.4:33143
I1104 00:08:37.562950 26738 hierarchical.cpp:325] Removed framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000
I1104 00:08:37.563482 26727 slave.cpp:5240] Executor 'default' of framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000 exited with status 0
I1104 00:08:37.563526 26727 slave.cpp:5344] Cleaning up executor 'default' of framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000 at executor(121)@172.17.0.4:33143
W1104 00:08:37.563554 26741 master.cpp:6594] Ignoring unknown exited executor 'default' of framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000 on agent 9d60c9d5-2096-461e-8478-2ccaa78943f9-S0 at slave(309)@172.17.0.4:33143 (e8c2c8e95099)
I1104 00:08:37.563745 26737 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_M1ZT1W/slaves/9d60c9d5-2096-461e-8478-2ccaa78943f9-S0/frameworks/9d60c9d5-2096-461e-8478-2ccaa78943f9-0000/executors/default/runs/3aac5ab7-f07a-4e44-898e-69fb326bbfb1' for gc 6.99999347671407days in the future
I1104 00:08:37.563841 26727 slave.cpp:5432] Cleaning up framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000
I1104 00:08:37.563911 26733 status_update_manager.cpp:285] Closing status update streams for framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000
I1104 00:08:37.563927 26733 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 9d60c9d5-2096-461e-8478-2ccaa78943f9-0000
I1104 00:08:37.563917 26739 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_M1ZT1W/slaves/9d60c9d5-2096-461e-8478-2ccaa78943f9-S0/frameworks/9d60c9d5-2096-461e-8478-2ccaa78943f9-0000/executors/default' for gc 6.9999934743763days in the future
I1104 00:08:37.563982 26739 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_M1ZT1W/slaves/9d60c9d5-2096-461e-8478-2ccaa78943f9-S0/frameworks/9d60c9d5-2096-461e-8478-2ccaa78943f9-0000' for gc 6.99999347321778days in the future
I1104 00:08:37.563993 26726 slave.cpp:796] Agent terminating
I1104 00:08:37.564244 26747 master.cpp:1313] Agent 9d60c9d5-2096-461e-8478-2ccaa78943f9-S0 at slave(309)@172.17.0.4:33143 (e8c2c8e95099) disconnected
I1104 00:08:37.564260 26747 master.cpp:3197] Disconnecting agent 9d60c9d5-2096-461e-8478-2ccaa78943f9-S0 at slave(309)@172.17.0.4:33143 (e8c2c8e95099)
I1104 00:08:37.564291 26747 master.cpp:3216] Deactivating agent 9d60c9d5-2096-461e-8478-2ccaa78943f9-S0 at slave(309)@172.17.0.4:33143 (e8c2c8e95099)
I1104 00:08:37.564375 26737 hierarchical.cpp:653] Agent 9d60c9d5-2096-461e-8478-2ccaa78943f9-S0 deactivated
I1104 00:08:37.565614 26740 master.cpp:1155] Master terminating
I1104 00:08:37.565855 26728 hierarchical.cpp:558] Removed agent 9d60c9d5-2096-461e-8478-2ccaa78943f9-S0
[ OK ] MasterTest.FrameworkMessage (37 ms)
[ RUN ] MasterTest.MultipleExecutors
I1104 00:08:37.569386 26726 cluster.cpp:162] Creating default 'local' authorizer
I1104 00:08:37.570652 26731 master.cpp:436] Master 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7 (e8c2c8e95099) started on 172.17.0.4:33143
I1104 00:08:37.570673 26731 master.cpp:438] 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/DG1U87/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" --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/DG1U87/master" --zk_session_timeout="10secs"
I1104 00:08:37.570796 26731 master.cpp:488] Master only allowing authenticated frameworks to register
I1104 00:08:37.570806 26731 master.cpp:502] Master only allowing authenticated agents to register
I1104 00:08:37.570811 26731 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I1104 00:08:37.570817 26731 credentials.hpp:37] Loading credentials for authentication from '/tmp/DG1U87/credentials'
I1104 00:08:37.570941 26731 master.cpp:560] Using default 'crammd5' authenticator
I1104 00:08:37.570986 26731 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I1104 00:08:37.571130 26731 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I1104 00:08:37.571207 26731 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I1104 00:08:37.571264 26731 master.cpp:640] Authorization enabled
I1104 00:08:37.571442 26737 hierarchical.cpp:158] Initialized hierarchical allocator process
I1104 00:08:37.571444 26728 whitelist_watcher.cpp:77] No whitelist given
I1104 00:08:37.572181 26739 master.cpp:2161] Elected as the leading master!
I1104 00:08:37.572192 26739 master.cpp:1700] Recovering from registrar
I1104 00:08:37.572243 26747 registrar.cpp:345] Recovering registrar
I1104 00:08:37.572551 26747 registrar.cpp:389] Successfully fetched the registry (0B) in 288us
I1104 00:08:37.572590 26747 registrar.cpp:493] Applied 1 operations in 9484ns; attempting to update the registry
I1104 00:08:37.572875 26747 registrar.cpp:550] Successfully updated the registry in 265984ns
I1104 00:08:37.572906 26747 registrar.cpp:422] Successfully recovered registrar
I1104 00:08:37.573143 26741 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I1104 00:08:37.573163 26747 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover
I1104 00:08:37.575201 26726 cluster.cpp:448] Creating default 'local' authorizer
I1104 00:08:37.575703 26729 slave.cpp:231] Mesos agent started on (310)@172.17.0.4:33143
I1104 00:08:37.575721 26729 slave.cpp:232] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/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/MasterTest_MultipleExecutors_LqlJOI/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_reregistration_timeout="2secs" --executor_secret_key="/tmp/MasterTest_MultipleExecutors_LqlJOI/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_LqlJOI/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/MasterTest_MultipleExecutors_LqlJOI/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/MasterTest_MultipleExecutors_LqlJOI" --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_AVGOtV"
I1104 00:08:37.575884 26729 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_LqlJOI/credential'
I1104 00:08:37.575959 26729 slave.cpp:264] Agent using credential for: test-principal
I1104 00:08:37.575969 26729 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_LqlJOI/http_credentials'
I1104 00:08:37.576097 26729 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I1104 00:08:37.576143 26729 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I1104 00:08:37.576298 26729 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1104 00:08:37.576387 26726 sched.cpp:232] Version: 1.3.2
I1104 00:08:37.576445 26729 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I1104 00:08:37.576529 26740 sched.cpp:336] New master detected at master@172.17.0.4:33143
I1104 00:08:37.576567 26740 sched.cpp:407] Authenticating with master master@172.17.0.4:33143
I1104 00:08:37.576578 26740 sched.cpp:414] Using default CRAM-MD5 authenticatee
I1104 00:08:37.576649 26727 authenticatee.cpp:121] Creating new client SASL connection
I1104 00:08:37.576650 26729 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1104 00:08:37.576800 26737 master.cpp:7477] Authenticating scheduler-2be41ea0-2605-4876-b147-8bee11dc97d4@172.17.0.4:33143
I1104 00:08:37.576853 26733 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(662)@172.17.0.4:33143
I1104 00:08:37.576875 26729 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I1104 00:08:37.577066 26727 authenticator.cpp:98] Creating new server SASL connection
I1104 00:08:37.577278 26739 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I1104 00:08:37.577299 26739 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I1104 00:08:37.577347 26740 authenticator.cpp:204] Received SASL authentication start
I1104 00:08:37.577399 26740 authenticator.cpp:326] Authentication requires more steps
I1104 00:08:37.577441 26740 authenticatee.cpp:259] Received SASL authentication step
I1104 00:08:37.577504 26730 authenticator.cpp:232] Received SASL authentication step
I1104 00:08:37.577527 26730 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e8c2c8e95099' server FQDN: 'e8c2c8e95099' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1104 00:08:37.577535 26730 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I1104 00:08:37.577544 26730 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1104 00:08:37.577553 26730 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e8c2c8e95099' server FQDN: 'e8c2c8e95099' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1104 00:08:37.577538 26729 slave.cpp:531] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1104 00:08:37.577559 26730 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1104 00:08:37.577571 26729 slave.cpp:539] Agent attributes: [ ]
I1104 00:08:37.577584 26730 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1104 00:08:37.577601 26729 slave.cpp:544] Agent hostname: e8c2c8e95099
I1104 00:08:37.577610 26730 authenticator.cpp:318] Authentication success
I1104 00:08:37.577658 26739 status_update_manager.cpp:177] Pausing sending status updates
I1104 00:08:37.577774 26741 master.cpp:7507] Successfully authenticated principal 'test-principal' at scheduler-2be41ea0-2605-4876-b147-8bee11dc97d4@172.17.0.4:33143
I1104 00:08:37.577821 26748 authenticatee.cpp:299] Authentication success
I1104 00:08:37.577848 26727 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(662)@172.17.0.4:33143
I1104 00:08:37.577970 26732 sched.cpp:513] Successfully authenticated with master master@172.17.0.4:33143
I1104 00:08:37.577983 26732 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.4:33143
I1104 00:08:37.578028 26732 sched.cpp:869] Will retry registration in 1.773154577secs if necessary
I1104 00:08:37.578106 26741 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_AVGOtV/meta'
I1104 00:08:37.578176 26744 master.cpp:2813] Received SUBSCRIBE call for framework 'default' at scheduler-2be41ea0-2605-4876-b147-8bee11dc97d4@172.17.0.4:33143
I1104 00:08:37.578192 26744 master.cpp:2197] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I1104 00:08:37.578233 26739 status_update_manager.cpp:203] Recovering status update manager
I1104 00:08:37.578418 26730 master.cpp:2890] Subscribing framework default with checkpointing disabled and capabilities [ ]
I1104 00:08:37.578594 26731 sched.cpp:759] Framework registered with 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000
I1104 00:08:37.578619 26749 hierarchical.cpp:273] Added framework 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000
I1104 00:08:37.578629 26750 slave.cpp:6100] Finished recovery
I1104 00:08:37.578629 26731 sched.cpp:773] Scheduler::registered took 29511ns
I1104 00:08:37.578660 26749 hierarchical.cpp:1850] No allocations performed
I1104 00:08:37.578668 26749 hierarchical.cpp:1940] No inverse offers to send out!
I1104 00:08:37.578675 26749 hierarchical.cpp:1434] Performed allocation for 0 agents in 25976ns
I1104 00:08:37.578951 26750 slave.cpp:6282] Querying resource estimator for oversubscribable resources
I1104 00:08:37.579042 26738 slave.cpp:924] New master detected at master@172.17.0.4:33143
I1104 00:08:37.579049 26748 status_update_manager.cpp:177] Pausing sending status updates
I1104 00:08:37.579088 26738 slave.cpp:959] Detecting new master
I1104 00:08:37.579217 26738 slave.cpp:6296] Received oversubscribable resources {} from the resource estimator
I1104 00:08:37.581197 26736 slave.cpp:986] Authenticating with master master@172.17.0.4:33143
I1104 00:08:37.581219 26736 slave.cpp:997] Using default CRAM-MD5 authenticatee
I1104 00:08:37.581279 26749 authenticatee.cpp:121] Creating new client SASL connection
I1104 00:08:37.581413 26742 master.cpp:7477] Authenticating slave(310)@172.17.0.4:33143
I1104 00:08:37.581504 26728 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(663)@172.17.0.4:33143
I1104 00:08:37.581576 26741 authenticator.cpp:98] Creating new server SASL connection
I1104 00:08:37.581727 26730 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I1104 00:08:37.581743 26730 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I1104 00:08:37.581820 26742 authenticator.cpp:204] Received SASL authentication start
I1104 00:08:37.581857 26742 authenticator.cpp:326] Authentication requires more steps
I1104 00:08:37.581918 26732 authenticatee.cpp:259] Received SASL authentication step
I1104 00:08:37.581985 26748 authenticator.cpp:232] Received SASL authentication step
I1104 00:08:37.582003 26748 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e8c2c8e95099' server FQDN: 'e8c2c8e95099' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1104 00:08:37.582012 26748 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I1104 00:08:37.582027 26748 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1104 00:08:37.582038 26748 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e8c2c8e95099' server FQDN: 'e8c2c8e95099' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1104 00:08:37.582044 26748 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1104 00:08:37.582048 26748 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1104 00:08:37.582057 26748 authenticator.cpp:318] Authentication success
I1104 00:08:37.582099 26729 authenticatee.cpp:299] Authentication success
I1104 00:08:37.582121 26727 master.cpp:7507] Successfully authenticated principal 'test-principal' at slave(310)@172.17.0.4:33143
I1104 00:08:37.582165 26748 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(663)@172.17.0.4:33143
I1104 00:08:37.582237 26747 slave.cpp:1081] Successfully authenticated with master master@172.17.0.4:33143
I1104 00:08:37.582304 26747 slave.cpp:1502] Will retry registration in 17.458565ms if necessary
I1104 00:08:37.582377 26748 master.cpp:5431] Received register agent message from slave(310)@172.17.0.4:33143 (e8c2c8e95099)
I1104 00:08:37.582393 26748 master.cpp:3659] Authorizing agent with principal 'test-principal'
I1104 00:08:37.582567 26747 master.cpp:5566] Registering agent at slave(310)@172.17.0.4:33143 (e8c2c8e95099) with id 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-S0
I1104 00:08:37.582669 26738 registrar.cpp:493] Applied 1 operations in 18502ns; attempting to update the registry
I1104 00:08:37.582830 26738 registrar.cpp:550] Successfully updated the registry in 142848ns
I1104 00:08:37.583123 26736 slave.cpp:4817] Received ping from slave-observer(303)@172.17.0.4:33143
I1104 00:08:37.583130 26729 master.cpp:5641] Registered agent 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-S0 at slave(310)@172.17.0.4:33143 (e8c2c8e95099) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1104 00:08:37.583200 26736 slave.cpp:1127] Registered with master master@172.17.0.4:33143; given agent ID 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-S0
I1104 00:08:37.583215 26736 fetcher.cpp:94] Clearing fetcher cache
I1104 00:08:37.583232 26738 hierarchical.cpp:525] Added agent 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-S0 (e8c2c8e95099) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I1104 00:08:37.583309 26742 status_update_manager.cpp:184] Resuming sending status updates
I1104 00:08:37.583470 26736 slave.cpp:1155] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_AVGOtV/meta/slaves/856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-S0/slave.info'
I1104 00:08:37.583487 26738 hierarchical.cpp:1940] No inverse offers to send out!
I1104 00:08:37.583503 26738 hierarchical.cpp:1434] Performed allocation for 1 agents in 226652ns
I1104 00:08:37.583613 26747 master.cpp:7307] Sending 1 offers to framework 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000 (default) at scheduler-2be41ea0-2605-4876-b147-8bee11dc97d4@172.17.0.4:33143
I1104 00:08:37.583670 26736 slave.cpp:1193] Forwarding total oversubscribed resources {}
I1104 00:08:37.583726 26729 master.cpp:6326] Received update of agent 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-S0 at slave(310)@172.17.0.4:33143 (e8c2c8e95099) with total oversubscribed resources {}
I1104 00:08:37.583782 26749 sched.cpp:933] Scheduler::resourceOffers took 53682ns
I1104 00:08:37.584584 26727 master.cpp:3875] Processing ACCEPT call for offers: [ 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-O0 ] on agent 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-S0 at slave(310)@172.17.0.4:33143 (e8c2c8e95099) for framework 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000 (default) at scheduler-2be41ea0-2605-4876-b147-8bee11dc97d4@172.17.0.4:33143
I1104 00:08:37.584617 26727 master.cpp:3426] Authorizing framework principal 'test-principal' to launch task 1
I1104 00:08:37.584687 26727 master.cpp:3426] Authorizing framework principal 'test-principal' to launch task 2
W1104 00:08:37.585211 26735 validation.cpp:1284] 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.
W1104 00:08:37.585230 26735 validation.cpp:1296] 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.
I1104 00:08:37.585295 26735 master.cpp:9322] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-S0 at slave(310)@172.17.0.4:33143 (e8c2c8e95099)
I1104 00:08:37.585336 26735 master.cpp:4531] Launching task 1 of framework 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000 (default) at scheduler-2be41ea0-2605-4876-b147-8bee11dc97d4@172.17.0.4:33143 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-S0 at slave(310)@172.17.0.4:33143 (e8c2c8e95099)
I1104 00:08:37.585458 26729 slave.cpp:1612] Got assigned task '1' for framework 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000
W1104 00:08:37.585559 26735 validation.cpp:1284] 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.
W1104 00:08:37.585574 26735 validation.cpp:1296] 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.
I1104 00:08:37.585634 26735 master.cpp:9322] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-S0 at slave(310)@172.17.0.4:33143 (e8c2c8e95099)
I1104 00:08:37.585647 26729 slave.cpp:1879] Authorizing task '1' for framework 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000
I1104 00:08:37.585665 26729 slave.cpp:6719] Authorizing framework principal 'test-principal' to launch task 1
I1104 00:08:37.585671 26735 master.cpp:4531] Launching task 2 of framework 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000 (default) at scheduler-2be41ea0-2605-4876-b147-8bee11dc97d4@172.17.0.4:33143 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-S0 at slave(310)@172.17.0.4:33143 (e8c2c8e95099)
I1104 00:08:37.585817 26729 slave.cpp:1612] Got assigned task '2' for framework 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000
I1104 00:08:37.586171 26729 slave.cpp:2047] Launching task '1' for framework 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000
I1104 00:08:37.586242 26745 hierarchical.cpp:850] Updated allocation of framework 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000 on agent 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-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]
I1104 00:08:37.586361 26745 hierarchical.cpp:1114] 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 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-S0 from framework 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000
I1104 00:08:37.586381 26745 hierarchical.cpp:1151] Framework 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000 filtered agent 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-S0 for 5secs
I1104 00:08:37.586534 26729 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_AVGOtV/slaves/856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-S0/frameworks/856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000/executors/executor-1/runs/d5c18a86-ea38-4d21-bc40-d8bbd27255cf' to user 'mesos'
I1104 00:08:37.586668 26729 slave.cpp:7069] Launching executor 'executor-1' of framework 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_AVGOtV/slaves/856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-S0/frameworks/856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000/executors/executor-1/runs/d5c18a86-ea38-4d21-bc40-d8bbd27255cf'
I1104 00:08:37.586850 26729 slave.cpp:2276] Queued task '1' for executor 'executor-1' of framework 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000
I1104 00:08:37.586886 26729 slave.cpp:1879] Authorizing task '2' for framework 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000
I1104 00:08:37.586902 26729 slave.cpp:6719] Authorizing framework principal 'test-principal' to launch task 2
I1104 00:08:37.587004 26729 slave.cpp:877] Successfully attached file '/tmp/MasterTest_MultipleExecutors_AVGOtV/slaves/856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-S0/frameworks/856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000/executors/executor-1/runs/d5c18a86-ea38-4d21-bc40-d8bbd27255cf'
I1104 00:08:37.587363 26729 slave.cpp:2047] Launching task '2' for framework 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000
I1104 00:08:37.587648 26729 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_AVGOtV/slaves/856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-S0/frameworks/856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000/executors/executor-2/runs/41a345ac-e1b3-4adc-a4b4-e6c09fa79f6d' to user 'mesos'
I1104 00:08:37.587810 26729 slave.cpp:7069] Launching executor 'executor-2' of framework 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_AVGOtV/slaves/856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-S0/frameworks/856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000/executors/executor-2/runs/41a345ac-e1b3-4adc-a4b4-e6c09fa79f6d'
I1104 00:08:37.588012 26729 slave.cpp:2276] Queued task '2' for executor 'executor-2' of framework 856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000
I1104 00:08:37.588044 26729 slave.cpp:877] Successfully attached file '/tmp/MasterTest_MultipleExecutors_AVGOtV/slaves/856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-S0/frameworks/856bd7c5-f1eb-445f-9e0d-4a8b1b63e7a7-0000/executors/executor-2/runs/41a345ac-e1b3-4adc-a4b4-e6c09fa79f6d'
*** Aborted at 1509754117 (unix time) try "date -d @1509754117" if you are using GNU date ***
I1104 00:08:37.588258 26740 exec.cpp:162] Version: 1.3.2
I1104 00:08:37.588310 26732 exec.cpp:212] Executor started at: executor(122)@172.17.0.4:33143 with pid 26726
PC: @ 0x2b0c4ddfc82d (unknown)
*** SIGSEGV (@0xb0) received by PID 26726 (TID 0x2b0c55183700) from PID 176; stack trace: ***
@ 0x2b0c4d393330 (unknown)
@ 0x2b0c4ddfc82d (unknown)
@ 0x2b0c48770e95 mesos::internal::slave::executorEnvironment()
@ 0x2b0c4876c054 mesos::internal::slave::Slave::launchExecutor()
@ 0x2b0c4a0dadf7 process::ProcessManager::resume()
@ 0x2b0c4a0effaf std::thread::_Impl<>::_M_run()
@ 0x2b0c4d652a60 (unknown)
@ 0x2b0c4d38b184 start_thread
@ 0x2b0c4debeffd (unknown)
make[3]: *** [CMakeFiles/check] Segmentation fault (core dumped)
make[3]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[2]: Leaving directory `/mesos/build'
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1509752173-764
Untagged: mesos-1509752173-764:latest
Deleted: sha256:a29a3c69a83215721891935dfe9ddd02ddecaf7cf6c9a2ea603c37b04e13a875
Deleted: sha256:f2c569fd41abf04e6a55900571cbc9af5194568417cc807de618f896d922b221
Deleted: sha256:0fb492012e9e7b96175213acd73985de76cc3035be6c5a530c3440f0ea3dd240
Deleted: sha256:7d6fe985e15bf6d3b5e33b74a352c5c659c4e8c6c84e55b5cdd12216472a042d
Deleted: sha256:183911a5dcf9cc411b67738f8f36d4e010b5b8faf2f1e0c34270fd15f647dbc6
Deleted: sha256:ac6e2e3f79e6924ce2fa76d894f3af6107ef690d48f10949d16b708696fc2e3e
Deleted: sha256:cf730df2b106c093af4cbd36dfd4d7afca8b2b5cce6521850440f2c03ffd9736
Deleted: sha256:08d5fafdc51dd94946325ad42cc4e4e9180cad3f7e0b34e7a74fdbdcdd53192a
Deleted: sha256:934fd98a97d40d4c61ec165463dc7e6a20f804907841c50f79af0ad7126db5dd
Deleted: sha256:abb892e154e5281b1597781618ec5d1616162f8c672fe64ad7406f1704eb5c3a
Deleted: sha256:4714882598b48b2622943deb8d208b0195715aba09e7286b1e692a7143cfb814
Deleted: sha256:3e977c72b9705b9b90557f1316f753446b149164d1e12182322c8c55439bde1d
Deleted: sha256:7de61a10748e3face6bd4da9f4db38bf8f1dfdf7087726fffc3dc2d1322fb9f4
Deleted: sha256:cec00aab8ec5751463a79fc457633c0d4ecfca216435074645d79d567f2a19bc
Deleted: sha256:6cba3b5e36daf46ffef09b4dccb9bbb7fca33e9c81fbdf791c6839fdaab72c3f
Deleted: sha256:6b3e0b1196ea296d01afd3e4be62d9a5251e149c0a5cd04077a07e0dbb5ea5ee
Deleted: sha256:9dc0746158c6c56bab983b91cc6eed794285cd64987af213312c8f4d404a009e
Deleted: sha256:b82fe6be42c7d40978593ff36989b56e1d529897132a358148c6e3e4fd3f1f30
Deleted: sha256:5124863a5592cb1ef62512ecabede1d28b7e09ac9c3a23e5f12e777eb1bc4e2e
Deleted: sha256:b3770ecc8004774fa3390d94075a9b09695df03ebf292b8e9e7b116869d9001f
Deleted: sha256:22188bb5f846b360e871252234554d95b049900ca0aef738ce8953401bff232d
Deleted: sha256:335f27dfdfcfe27d2151398b4cb8c6cbc1165c7638b08b04a390ce38987927be
Deleted: sha256:4ebb5d16e99b76d6557d59cb95b7a69f9c804b93a70a349679b8bc0ccf3a8a67
Deleted: sha256:86665fe1bcfbadded262626d2cec9a5dfcffab28aa546445fb87b1e44e911d18
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » cmake,clang,--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4403
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers%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)/4403/display/redirect?page=changes>