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/06/12 17:02:15 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3784
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3784/display/redirect?page=changes>
Changes:
[klueska] Updated 'config.py' in the new Mesos CLI to take settings as parameter.
[klueska] Extended the unit test infrastructure in the new Mesos CLI.
------------------------------------------
[...truncated 13.64 MB...]
I0612 17:02:09.336097 1832 master.cpp:5232] Processing ACKNOWLEDGE call 9116ffa4-8607-4849-bd72-c81f150c507d for task 1 of framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000 (default) at scheduler-ab02daaf-1314-4edb-a1ad-0cb13f518e22@172.17.0.3:34807 on agent 709e4d59-5b2a-4d94-b4ed-84db0876133e-S0
I0612 17:02:09.336282 1826 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 9116ffa4-8607-4849-bd72-c81f150c507d) for task 1 of framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000
I0612 17:02:09.336351 1823 exec.cpp:416] Executor received framework message
I0612 17:02:09.336398 1823 exec.cpp:425] Executor::frameworkMessage took 31807ns
I0612 17:02:09.336508 1834 slave.cpp:3545] Status update manager successfully handled status update acknowledgement (UUID: 9116ffa4-8607-4849-bd72-c81f150c507d) for task 1 of framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000
I0612 17:02:09.336597 1838 slave.cpp:4803] Sending message for framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000 to scheduler-ab02daaf-1314-4edb-a1ad-0cb13f518e22@172.17.0.3:34807
I0612 17:02:09.336685 1830 sched.cpp:1177] Scheduler::frameworkMessage took 23302ns
I0612 17:02:09.336781 1815 sched.cpp:2021] Asked to stop the driver
I0612 17:02:09.336823 1836 sched.cpp:1203] Stopping framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000
I0612 17:02:09.336895 1816 master.cpp:7976] Processing TEARDOWN call for framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000 (default) at scheduler-ab02daaf-1314-4edb-a1ad-0cb13f518e22@172.17.0.3:34807
I0612 17:02:09.336917 1816 master.cpp:7988] Removing framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000 (default) at scheduler-ab02daaf-1314-4edb-a1ad-0cb13f518e22@172.17.0.3:34807
I0612 17:02:09.336931 1816 master.cpp:3200] Deactivating framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000 (default) at scheduler-ab02daaf-1314-4edb-a1ad-0cb13f518e22@172.17.0.3:34807
I0612 17:02:09.337013 1838 hierarchical.cpp:410] Deactivated framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000
I0612 17:02:09.337059 1816 master.cpp:8556] Updating the state of task 1 of framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0612 17:02:09.337069 1824 slave.cpp:3098] Asked to shut down framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000 by master@172.17.0.3:34807
I0612 17:02:09.337085 1824 slave.cpp:3123] Shutting down framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000
I0612 17:02:09.337100 1824 slave.cpp:5590] Shutting down executor 'default' of framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000 at executor(124)@172.17.0.3:34807
I0612 17:02:09.337265 1816 master.cpp:8650] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000 on agent 709e4d59-5b2a-4d94-b4ed-84db0876133e-S0 at slave(315)@172.17.0.3:34807 (53af1be5bfda)
I0612 17:02:09.337328 1820 hierarchical.cpp:1177] 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 709e4d59-5b2a-4d94-b4ed-84db0876133e-S0 from framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000
I0612 17:02:09.337354 1816 master.cpp:8679] Removing executor 'default' with resources {} of framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000 on agent 709e4d59-5b2a-4d94-b4ed-84db0876133e-S0 at slave(315)@172.17.0.3:34807 (53af1be5bfda)
I0612 17:02:09.337435 1818 exec.cpp:435] Executor asked to shutdown
I0612 17:02:09.337457 1818 exec.cpp:450] Executor::shutdown took 10160ns
I0612 17:02:09.337607 1834 slave.cpp:4867] Got exited event for executor(124)@172.17.0.3:34807
I0612 17:02:09.337621 1827 hierarchical.cpp:353] Removed framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000
I0612 17:02:09.337920 1827 slave.cpp:5268] Executor 'default' of framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000 exited with status 0
I0612 17:02:09.338029 1827 slave.cpp:5368] Cleaning up executor 'default' of framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000 at executor(124)@172.17.0.3:34807
W0612 17:02:09.338109 1835 master.cpp:6600] Ignoring unknown exited executor 'default' of framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000 on agent 709e4d59-5b2a-4d94-b4ed-84db0876133e-S0 at slave(315)@172.17.0.3:34807 (53af1be5bfda)
I0612 17:02:09.338241 1817 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_xcU812/slaves/709e4d59-5b2a-4d94-b4ed-84db0876133e-S0/frameworks/709e4d59-5b2a-4d94-b4ed-84db0876133e-0000/executors/default/runs/e6e0a263-b262-4a57-9256-a8342f8f6c56' for gc 6.99999608660741days in the future
I0612 17:02:09.338393 1827 slave.cpp:5464] Cleaning up framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000
I0612 17:02:09.338397 1834 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_xcU812/slaves/709e4d59-5b2a-4d94-b4ed-84db0876133e-S0/frameworks/709e4d59-5b2a-4d94-b4ed-84db0876133e-0000/executors/default' for gc 6.99999608545185days in the future
I0612 17:02:09.338443 1834 status_update_manager.cpp:285] Closing status update streams for framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000
I0612 17:02:09.338459 1834 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 709e4d59-5b2a-4d94-b4ed-84db0876133e-0000
I0612 17:02:09.338518 1827 slave.cpp:817] Agent terminating
I0612 17:02:09.338517 1819 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_xcU812/slaves/709e4d59-5b2a-4d94-b4ed-84db0876133e-S0/frameworks/709e4d59-5b2a-4d94-b4ed-84db0876133e-0000' for gc 6.99999608242963days in the future
I0612 17:02:09.338599 1820 master.cpp:1314] Agent 709e4d59-5b2a-4d94-b4ed-84db0876133e-S0 at slave(315)@172.17.0.3:34807 (53af1be5bfda) disconnected
I0612 17:02:09.338616 1820 master.cpp:3237] Disconnecting agent 709e4d59-5b2a-4d94-b4ed-84db0876133e-S0 at slave(315)@172.17.0.3:34807 (53af1be5bfda)
I0612 17:02:09.338639 1820 master.cpp:3256] Deactivating agent 709e4d59-5b2a-4d94-b4ed-84db0876133e-S0 at slave(315)@172.17.0.3:34807 (53af1be5bfda)
I0612 17:02:09.338685 1835 hierarchical.cpp:715] Agent 709e4d59-5b2a-4d94-b4ed-84db0876133e-S0 deactivated
I0612 17:02:09.339785 1815 master.cpp:1156] Master terminating
I0612 17:02:09.340001 1833 hierarchical.cpp:620] Removed agent 709e4d59-5b2a-4d94-b4ed-84db0876133e-S0
[ OK ] MasterTest.FrameworkMessage (29 ms)
[ RUN ] MasterTest.MultipleExecutors
I0612 17:02:09.342980 1815 cluster.cpp:162] Creating default 'local' authorizer
I0612 17:02:09.343905 1838 master.cpp:436] Master 11445268-cb9f-4e61-a175-2a819ad1f60b (53af1be5bfda) started on 172.17.0.3:34807
I0612 17:02:09.343919 1838 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/1wmGCg/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/1wmGCg/master" --zk_session_timeout="10secs"
I0612 17:02:09.344045 1838 master.cpp:488] Master only allowing authenticated frameworks to register
I0612 17:02:09.344053 1838 master.cpp:502] Master only allowing authenticated agents to register
I0612 17:02:09.344058 1838 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0612 17:02:09.344064 1838 credentials.hpp:37] Loading credentials for authentication from '/tmp/1wmGCg/credentials'
I0612 17:02:09.344173 1838 master.cpp:560] Using default 'crammd5' authenticator
I0612 17:02:09.344213 1838 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0612 17:02:09.344249 1838 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0612 17:02:09.344358 1838 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0612 17:02:09.344434 1838 master.cpp:640] Authorization enabled
I0612 17:02:09.344549 1826 whitelist_watcher.cpp:77] No whitelist given
I0612 17:02:09.344563 1835 hierarchical.cpp:169] Initialized hierarchical allocator process
I0612 17:02:09.345227 1838 master.cpp:2159] Elected as the leading master!
I0612 17:02:09.345239 1838 master.cpp:1698] Recovering from registrar
I0612 17:02:09.345289 1834 registrar.cpp:345] Recovering registrar
I0612 17:02:09.345537 1834 registrar.cpp:389] Successfully fetched the registry (0B) in 231936ns
I0612 17:02:09.345563 1834 registrar.cpp:493] Applied 1 operations in 4498ns; attempting to update the registry
I0612 17:02:09.345705 1827 registrar.cpp:550] Successfully updated the registry in 119808ns
I0612 17:02:09.345748 1827 registrar.cpp:422] Successfully recovered registrar
I0612 17:02:09.345937 1816 hierarchical.cpp:207] Skipping recovery of hierarchical allocator: nothing to recover
I0612 17:02:09.345944 1821 master.cpp:1797] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0612 17:02:09.347656 1815 cluster.cpp:448] Creating default 'local' authorizer
I0612 17:02:09.348111 1826 slave.cpp:248] Mesos agent started on (316)@172.17.0.3:34807
I0612 17:02:09.348121 1826 slave.cpp:249] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/MasterTest_MultipleExecutors_E7Hwvn/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_E7Hwvn/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/MasterTest_MultipleExecutors_E7Hwvn/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_E7Hwvn/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_E7Hwvn/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_E7Hwvn/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_E7Hwvn" --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_Yfa63O"
I0612 17:02:09.348280 1826 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_E7Hwvn/credential'
I0612 17:02:09.348342 1826 slave.cpp:281] Agent using credential for: test-principal
I0612 17:02:09.348352 1826 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_E7Hwvn/http_credentials'
I0612 17:02:09.348470 1826 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0612 17:02:09.348505 1826 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0612 17:02:09.348559 1826 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0612 17:02:09.348598 1826 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0612 17:02:09.348671 1826 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0612 17:02:09.348676 1815 sched.cpp:232] Version: 1.4.0
I0612 17:02:09.348825 1824 sched.cpp:336] New master detected at master@172.17.0.3:34807
I0612 17:02:09.348858 1824 sched.cpp:407] Authenticating with master master@172.17.0.3:34807
I0612 17:02:09.348871 1824 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0612 17:02:09.348879 1826 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0612 17:02:09.348935 1832 authenticatee.cpp:121] Creating new client SASL connection
I0612 17:02:09.349170 1827 master.cpp:7465] Authenticating scheduler-9fb5795e-df8e-4179-a7b8-b43975ddcfde@172.17.0.3:34807
I0612 17:02:09.349225 1834 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(668)@172.17.0.3:34807
I0612 17:02:09.349294 1835 authenticator.cpp:98] Creating new server SASL connection
I0612 17:02:09.349413 1818 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0612 17:02:09.349432 1818 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0612 17:02:09.349427 1826 slave.cpp:552] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0612 17:02:09.349455 1826 slave.cpp:560] Agent attributes: [ ]
I0612 17:02:09.349462 1826 slave.cpp:565] Agent hostname: 53af1be5bfda
I0612 17:02:09.349484 1817 authenticator.cpp:204] Received SASL authentication start
I0612 17:02:09.349525 1817 authenticator.cpp:326] Authentication requires more steps
I0612 17:02:09.349524 1820 status_update_manager.cpp:177] Pausing sending status updates
I0612 17:02:09.349588 1820 authenticatee.cpp:259] Received SASL authentication step
I0612 17:02:09.349650 1820 authenticator.cpp:232] Received SASL authentication step
I0612 17:02:09.349673 1820 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '53af1be5bfda' server FQDN: '53af1be5bfda' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0612 17:02:09.349684 1820 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0612 17:02:09.349696 1820 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0612 17:02:09.349720 1820 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '53af1be5bfda' server FQDN: '53af1be5bfda' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0612 17:02:09.349731 1820 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0612 17:02:09.349740 1820 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0612 17:02:09.349758 1820 authenticator.cpp:318] Authentication success
I0612 17:02:09.349795 1821 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_Yfa63O/meta'
I0612 17:02:09.349838 1825 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(668)@172.17.0.3:34807
I0612 17:02:09.349870 1831 authenticatee.cpp:299] Authentication success
I0612 17:02:09.349876 1839 master.cpp:7495] Successfully authenticated principal 'test-principal' at scheduler-9fb5795e-df8e-4179-a7b8-b43975ddcfde@172.17.0.3:34807
I0612 17:02:09.349977 1828 status_update_manager.cpp:203] Recovering status update manager
I0612 17:02:09.350078 1831 sched.cpp:513] Successfully authenticated with master master@172.17.0.3:34807
I0612 17:02:09.350096 1831 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.3:34807
I0612 17:02:09.350175 1838 slave.cpp:6127] Finished recovery
I0612 17:02:09.350203 1831 sched.cpp:869] Will retry registration in 932.116539ms if necessary
I0612 17:02:09.350248 1816 master.cpp:2850] Received SUBSCRIBE call for framework 'default' at scheduler-9fb5795e-df8e-4179-a7b8-b43975ddcfde@172.17.0.3:34807
I0612 17:02:09.350272 1816 master.cpp:2195] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0612 17:02:09.350409 1826 master.cpp:2930] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0612 17:02:09.350500 1838 slave.cpp:6309] Querying resource estimator for oversubscribable resources
I0612 17:02:09.350601 1820 hierarchical.cpp:301] Added framework 11445268-cb9f-4e61-a175-2a819ad1f60b-0000
I0612 17:02:09.350639 1828 sched.cpp:759] Framework registered with 11445268-cb9f-4e61-a175-2a819ad1f60b-0000
I0612 17:02:09.350651 1820 hierarchical.cpp:1911] No allocations performed
I0612 17:02:09.350661 1820 hierarchical.cpp:2001] No inverse offers to send out!
I0612 17:02:09.350672 1828 sched.cpp:773] Scheduler::registered took 17769ns
I0612 17:02:09.350672 1820 hierarchical.cpp:1493] Performed allocation for 0 agents in 25278ns
I0612 17:02:09.350764 1838 slave.cpp:945] New master detected at master@172.17.0.3:34807
I0612 17:02:09.350770 1817 status_update_manager.cpp:177] Pausing sending status updates
I0612 17:02:09.350801 1838 slave.cpp:980] Detecting new master
I0612 17:02:09.350875 1838 slave.cpp:6323] Received oversubscribable resources {} from the resource estimator
I0612 17:02:09.354017 1831 slave.cpp:1007] Authenticating with master master@172.17.0.3:34807
I0612 17:02:09.354037 1831 slave.cpp:1018] Using default CRAM-MD5 authenticatee
I0612 17:02:09.354089 1819 authenticatee.cpp:121] Creating new client SASL connection
I0612 17:02:09.354208 1829 master.cpp:7465] Authenticating slave(316)@172.17.0.3:34807
I0612 17:02:09.354252 1823 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(669)@172.17.0.3:34807
I0612 17:02:09.354321 1818 authenticator.cpp:98] Creating new server SASL connection
I0612 17:02:09.354442 1838 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0612 17:02:09.354460 1838 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0612 17:02:09.354511 1818 authenticator.cpp:204] Received SASL authentication start
I0612 17:02:09.354552 1818 authenticator.cpp:326] Authentication requires more steps
I0612 17:02:09.354598 1830 authenticatee.cpp:259] Received SASL authentication step
I0612 17:02:09.354677 1839 authenticator.cpp:232] Received SASL authentication step
I0612 17:02:09.354697 1839 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '53af1be5bfda' server FQDN: '53af1be5bfda' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0612 17:02:09.354710 1839 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0612 17:02:09.354719 1839 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0612 17:02:09.354733 1839 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '53af1be5bfda' server FQDN: '53af1be5bfda' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0612 17:02:09.354740 1839 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0612 17:02:09.354745 1839 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0612 17:02:09.354755 1839 authenticator.cpp:318] Authentication success
I0612 17:02:09.354805 1817 authenticatee.cpp:299] Authentication success
I0612 17:02:09.354818 1824 master.cpp:7495] Successfully authenticated principal 'test-principal' at slave(316)@172.17.0.3:34807
I0612 17:02:09.354830 1830 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(669)@172.17.0.3:34807
I0612 17:02:09.354912 1835 slave.cpp:1102] Successfully authenticated with master master@172.17.0.3:34807
I0612 17:02:09.354964 1835 slave.cpp:1522] Will retry registration in 13.32718ms if necessary
I0612 17:02:09.355000 1825 master.cpp:5467] Received register agent message from slave(316)@172.17.0.3:34807 (53af1be5bfda)
I0612 17:02:09.355034 1825 master.cpp:3699] Authorizing agent with principal 'test-principal'
I0612 17:02:09.355176 1817 master.cpp:5527] Authorized registration of agent at slave(316)@172.17.0.3:34807 (53af1be5bfda)
I0612 17:02:09.355211 1817 master.cpp:5606] Registering agent at slave(316)@172.17.0.3:34807 (53af1be5bfda) with id 11445268-cb9f-4e61-a175-2a819ad1f60b-S0
I0612 17:02:09.355312 1822 registrar.cpp:493] Applied 1 operations in 10550ns; attempting to update the registry
I0612 17:02:09.355456 1822 registrar.cpp:550] Successfully updated the registry in 122880ns
I0612 17:02:09.355526 1824 master.cpp:5653] Admitted agent 11445268-cb9f-4e61-a175-2a819ad1f60b-S0 at slave(316)@172.17.0.3:34807 (53af1be5bfda)
I0612 17:02:09.355643 1822 slave.cpp:4825] Received ping from slave-observer(307)@172.17.0.3:34807
I0612 17:02:09.355758 1828 hierarchical.cpp:587] Added agent 11445268-cb9f-4e61-a175-2a819ad1f60b-S0 (53af1be5bfda) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0612 17:02:09.355787 1824 master.cpp:5684] Registered agent 11445268-cb9f-4e61-a175-2a819ad1f60b-S0 at slave(316)@172.17.0.3:34807 (53af1be5bfda) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0612 17:02:09.355823 1823 slave.cpp:1148] Registered with master master@172.17.0.3:34807; given agent ID 11445268-cb9f-4e61-a175-2a819ad1f60b-S0
I0612 17:02:09.355882 1831 status_update_manager.cpp:184] Resuming sending status updates
I0612 17:02:09.356029 1828 hierarchical.cpp:2001] No inverse offers to send out!
I0612 17:02:09.356040 1823 slave.cpp:1168] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_Yfa63O/meta/slaves/11445268-cb9f-4e61-a175-2a819ad1f60b-S0/slave.info'
I0612 17:02:09.356046 1828 hierarchical.cpp:1493] Performed allocation for 1 agents in 215439ns
I0612 17:02:09.356139 1822 master.cpp:7295] Sending 1 offers to framework 11445268-cb9f-4e61-a175-2a819ad1f60b-0000 (default) at scheduler-9fb5795e-df8e-4179-a7b8-b43975ddcfde@172.17.0.3:34807
I0612 17:02:09.356230 1823 slave.cpp:1206] Forwarding total oversubscribed resources {}
I0612 17:02:09.356273 1818 sched.cpp:933] Scheduler::resourceOffers took 31234ns
I0612 17:02:09.356303 1822 master.cpp:6338] Received update of agent 11445268-cb9f-4e61-a175-2a819ad1f60b-S0 at slave(316)@172.17.0.3:34807 (53af1be5bfda) with total oversubscribed resources {}
I0612 17:02:09.356832 1825 master.cpp:3914] Processing ACCEPT call for offers: [ 11445268-cb9f-4e61-a175-2a819ad1f60b-O0 ] on agent 11445268-cb9f-4e61-a175-2a819ad1f60b-S0 at slave(316)@172.17.0.3:34807 (53af1be5bfda) for framework 11445268-cb9f-4e61-a175-2a819ad1f60b-0000 (default) at scheduler-9fb5795e-df8e-4179-a7b8-b43975ddcfde@172.17.0.3:34807
I0612 17:02:09.356870 1825 master.cpp:3466] Authorizing framework principal 'test-principal' to launch task 1
I0612 17:02:09.356945 1825 master.cpp:3466] Authorizing framework principal 'test-principal' to launch task 2
W0612 17:02:09.357410 1835 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.
W0612 17:02:09.357430 1835 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.
I0612 17:02:09.357496 1835 master.cpp:9314] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 11445268-cb9f-4e61-a175-2a819ad1f60b-S0 at slave(316)@172.17.0.3:34807 (53af1be5bfda)
I0612 17:02:09.357544 1835 master.cpp:4569] Launching task 1 of framework 11445268-cb9f-4e61-a175-2a819ad1f60b-0000 (default) at scheduler-9fb5795e-df8e-4179-a7b8-b43975ddcfde@172.17.0.3:34807 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 11445268-cb9f-4e61-a175-2a819ad1f60b-S0 at slave(316)@172.17.0.3:34807 (53af1be5bfda)
I0612 17:02:09.357681 1823 slave.cpp:1632] Got assigned task '1' for framework 11445268-cb9f-4e61-a175-2a819ad1f60b-0000
W0612 17:02:09.357767 1835 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.
W0612 17:02:09.357785 1835 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.
I0612 17:02:09.357846 1835 master.cpp:9314] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 11445268-cb9f-4e61-a175-2a819ad1f60b-S0 at slave(316)@172.17.0.3:34807 (53af1be5bfda)
I0612 17:02:09.357887 1835 master.cpp:4569] Launching task 2 of framework 11445268-cb9f-4e61-a175-2a819ad1f60b-0000 (default) at scheduler-9fb5795e-df8e-4179-a7b8-b43975ddcfde@172.17.0.3:34807 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 11445268-cb9f-4e61-a175-2a819ad1f60b-S0 at slave(316)@172.17.0.3:34807 (53af1be5bfda)
I0612 17:02:09.357899 1823 slave.cpp:1913] Authorizing task '1' for framework 11445268-cb9f-4e61-a175-2a819ad1f60b-0000
I0612 17:02:09.357929 1823 slave.cpp:6626] Authorizing framework principal 'test-principal' to launch task 1
I0612 17:02:09.358104 1823 slave.cpp:1632] Got assigned task '2' for framework 11445268-cb9f-4e61-a175-2a819ad1f60b-0000
I0612 17:02:09.358263 1823 slave.cpp:2100] Launching task '1' for framework 11445268-cb9f-4e61-a175-2a819ad1f60b-0000
I0612 17:02:09.358398 1839 hierarchical.cpp:912] Updated allocation of framework 11445268-cb9f-4e61-a175-2a819ad1f60b-0000 on agent 11445268-cb9f-4e61-a175-2a819ad1f60b-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]
I0612 17:02:09.358530 1839 hierarchical.cpp:1177] 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 11445268-cb9f-4e61-a175-2a819ad1f60b-S0 from framework 11445268-cb9f-4e61-a175-2a819ad1f60b-0000
I0612 17:02:09.358554 1839 hierarchical.cpp:1214] Framework 11445268-cb9f-4e61-a175-2a819ad1f60b-0000 filtered agent 11445268-cb9f-4e61-a175-2a819ad1f60b-S0 for 5secs
I0612 17:02:09.358618 1823 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_Yfa63O/slaves/11445268-cb9f-4e61-a175-2a819ad1f60b-S0/frameworks/11445268-cb9f-4e61-a175-2a819ad1f60b-0000/executors/executor-1/runs/6da87044-b502-47d5-935e-24b2d0ec81a2' to user 'mesos'
I0612 17:02:09.358739 1823 slave.cpp:7086] Launching executor 'executor-1' of framework 11445268-cb9f-4e61-a175-2a819ad1f60b-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_Yfa63O/slaves/11445268-cb9f-4e61-a175-2a819ad1f60b-S0/frameworks/11445268-cb9f-4e61-a175-2a819ad1f60b-0000/executors/executor-1/runs/6da87044-b502-47d5-935e-24b2d0ec81a2'
I0612 17:02:09.358953 1823 slave.cpp:2329] Queued task '1' for executor 'executor-1' of framework 11445268-cb9f-4e61-a175-2a819ad1f60b-0000
I0612 17:02:09.358990 1823 slave.cpp:1913] Authorizing task '2' for framework 11445268-cb9f-4e61-a175-2a819ad1f60b-0000
I0612 17:02:09.359005 1823 slave.cpp:6626] Authorizing framework principal 'test-principal' to launch task 2
I0612 17:02:09.359118 1823 slave.cpp:898] Successfully attached file '/tmp/MasterTest_MultipleExecutors_Yfa63O/slaves/11445268-cb9f-4e61-a175-2a819ad1f60b-S0/frameworks/11445268-cb9f-4e61-a175-2a819ad1f60b-0000/executors/executor-1/runs/6da87044-b502-47d5-935e-24b2d0ec81a2'
I0612 17:02:09.359143 1823 slave.cpp:898] Successfully attached file '/tmp/MasterTest_MultipleExecutors_Yfa63O/slaves/11445268-cb9f-4e61-a175-2a819ad1f60b-S0/frameworks/11445268-cb9f-4e61-a175-2a819ad1f60b-0000/executors/executor-1/runs/6da87044-b502-47d5-935e-24b2d0ec81a2'
I0612 17:02:09.359256 1823 slave.cpp:2795] Launching container 6da87044-b502-47d5-935e-24b2d0ec81a2 for executor 'executor-1' of framework 11445268-cb9f-4e61-a175-2a819ad1f60b-0000
I0612 17:02:09.359402 1823 slave.cpp:2100] Launching task '2' for framework 11445268-cb9f-4e61-a175-2a819ad1f60b-0000
I0612 17:02:09.359647 1823 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_Yfa63O/slaves/11445268-cb9f-4e61-a175-2a819ad1f60b-S0/frameworks/11445268-cb9f-4e61-a175-2a819ad1f60b-0000/executors/executor-2/runs/71e9dd31-214e-49e6-82bb-3213b56686d3' to user 'mesos'
I0612 17:02:09.359764 1823 slave.cpp:7086] Launching executor 'executor-2' of framework 11445268-cb9f-4e61-a175-2a819ad1f60b-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_Yfa63O/slaves/11445268-cb9f-4e61-a175-2a819ad1f60b-S0/frameworks/11445268-cb9f-4e61-a175-2a819ad1f60b-0000/executors/executor-2/runs/71e9dd31-214e-49e6-82bb-3213b56686d3'
I0612 17:02:09.359956 1823 slave.cpp:2329] Queued task '2' for executor 'executor-2' of framework 11445268-cb9f-4e61-a175-2a819ad1f60b-0000
I0612 17:02:09.359988 1823 slave.cpp:898] Successfully attached file '/tmp/MasterTest_MultipleExecutors_Yfa63O/slaves/11445268-cb9f-4e61-a175-2a819ad1f60b-S0/frameworks/11445268-cb9f-4e61-a175-2a819ad1f60b-0000/executors/executor-2/runs/71e9dd31-214e-49e6-82bb-3213b56686d3'
*** Aborted at 1497286929 (unix time) try "date -d @1497286929" if you are using GNU date ***
I0612 17:02:09.360206 1828 exec.cpp:162] Version: 1.4.0
I0612 17:02:09.360275 1818 exec.cpp:212] Executor started at: executor(125)@172.17.0.3:34807 with pid 1815
PC: @ 0x7f1817fd80cd __GI_getenv
*** SIGSEGV (@0x20) received by PID 1815 (TID 0x7f180f0b8700) from PID 32; stack trace: ***
@ 0x7f1818b91370 (unknown)
@ 0x7f1817fd80cd __GI_getenv
@ 0x7f181cf148e4 mesos::internal::slave::executorEnvironment()
@ 0x7f181cf35dd9 mesos::internal::slave::Slave::launchExecutor()
@ 0x7f181c1136f1 process::ProcessManager::resume()
@ 0x7f181c11d037 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x7f181892e230 (unknown)
@ 0x7f1818b89dc5 start_thread
@ 0x7f181809776d __clone
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-1497283962-2437
Untagged: mesos-1497283962-2437:latest
Deleted: sha256:f62b223cf84a759afb2f22abd801aea713e44bd7a0f789090389b4d57f7214ff
Deleted: sha256:72c5204829b915f53aa71795e190d7b5cdad76839456b841e6927fff4594df8c
Deleted: sha256:9271d1d6069325203b2d062efe5880007abd74da9ebaef441ae4f03c553e6126
Deleted: sha256:e46063adb43e829391dd7f7d8b09cf23efb7f742bd1e8be97b924fd11ee8ba31
Deleted: sha256:44f094109e6232f9b140ef07a6c21761e1ab02101ebc488d63af82ac35977497
Deleted: sha256:b66ee895ccaa16ddabd704a03cebbb9b625cb0b3028d4bce142828e598b07923
Deleted: sha256:19c8d50863ed33fc6f8c4f236ada97068e53d94f1a01a9f98f079b60e8990af4
Deleted: sha256:9783c78b462c5229a889196b355527af0fcf37c7485f9c7e129c5072ba76fdec
Deleted: sha256:088e222ede5334e6206dfda372b97f120c93ebcf6e9f7ea57efa41682cce4e9e
Deleted: sha256:5f70e34c47d67f6aea8b7923494e428e95b0234917d36bb65b4a00d7aa3e7cb6
Deleted: sha256:64a6364a77d4a64efda31dee8f09ad862f239e6890db2b5c2f6c9ae1bee9faaa
Deleted: sha256:62f5029c7a2c6b2a65523771b9f9735673a275a78374e82c9b8cf320761a5188
Deleted: sha256:8a160f1e64124952ac37a980c98dc6913a8dbb6829681b3144c01ac01be57263
Deleted: sha256:9844821133fb008375fb0afee6e4fdf502a086582dc5bf25168da37ca6547463
Deleted: sha256:135bd2c1e4ab4cc7f448eb93fb60e43e136dc4135381c0489767b5b01af3f0a8
Deleted: sha256:c786e260625fcb3b9853e8ef75f9b4a1c19c02ad41fc26d626bdbe12767d280b
Deleted: sha256:46898ff317ee91b3e78417317497618a35884f0d8ea83a23e0ba2456afa8e7af
Deleted: sha256:cab7ad85407c447eec7b03fa02c13bc04455ce243d4579501707695f1538f24a
Deleted: sha256:29bb96ab47804f6b3c2efabe06f0313bd713340bf17347710aa809a6cb6e658d
Deleted: sha256:8ab34c5df3bffe87123fc4555ff522eb0918c777fce39515b762e50204d65f7c
Deleted: sha256:9f8746ba187ce2c4c197960dc117a62c77730033fdd3d18e86630b9f79574d81
Deleted: sha256:5a399c552a3d3628d0d78d996e0dd660a965679c2b2296c95a4caadb1d8635ac
Deleted: sha256:61378a32544a3ade786c3d1ae8573682b3787bdb18491735ca853464594de86d
Deleted: sha256:91b0f2a8220166cb081b9bd44e0e9f242ce6b206257a8b6392e4bdca7f0083d4
Deleted: sha256:814137991c05c06e31c429919a6e7a208e6c08e2726fb67c4a0f3333dccc17ef
Build step 'Execute shell' marked build as failure