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 18:27:02 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3784
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=(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 23.96 MB...]
I0612 18:26:51.897284 32223 master.cpp:6483] Status update TASK_RUNNING (UUID: d088e35c-4244-4588-a92b-e4a1dd255066) for task 1 of framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000 from agent 165b9039-d920-477a-aacb-f1cc63dbcf69-S0 at slave(689)@172.17.0.2:40041 (366b2e5ef4a2)
I0612 18:26:51.897351 32223 master.cpp:6545] Forwarding status update TASK_RUNNING (UUID: d088e35c-4244-4588-a92b-e4a1dd255066) for task 1 of framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000
I0612 18:26:51.897558 32223 master.cpp:8556] Updating the state of task 1 of framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0612 18:26:51.898185 32218 executor.cpp:725] Enqueuing event ACKNOWLEDGED received from http://172.17.0.2:40041/slave(689)/api/v1/executor
I0612 18:26:51.898329 32220 scheduler.cpp:676] Enqueuing event UPDATE received from http://172.17.0.2:40041/master/api/v1/scheduler
I0612 18:26:51.900115 32221 master.cpp:1428] Framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000 (default) disconnected
I0612 18:26:51.900149 32221 master.cpp:3200] Deactivating framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000 (default)
I0612 18:26:51.900179 32221 master.cpp:3177] Disconnecting framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000 (default)
I0612 18:26:51.900200 32221 master.cpp:1443] Giving framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000 (default) 0ns to failover
I0612 18:26:51.900398 32221 hierarchical.cpp:410] Deactivated framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000
I0612 18:26:51.901579 32221 master.cpp:7136] Framework failover timeout, removing framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000 (default)
I0612 18:26:51.901617 32221 master.cpp:7988] Removing framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000 (default)
I0612 18:26:51.901698 32221 master.cpp:8556] Updating the state of task 1 of framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0612 18:26:51.901952 32221 master.cpp:8650] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000 on agent 165b9039-d920-477a-aacb-f1cc63dbcf69-S0 at slave(689)@172.17.0.2:40041 (366b2e5ef4a2)
I0612 18:26:51.902117 32221 master.cpp:8679] Removing executor 'default' with resources {} of framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000 on agent 165b9039-d920-477a-aacb-f1cc63dbcf69-S0 at slave(689)@172.17.0.2:40041 (366b2e5ef4a2)
I0612 18:26:51.902251 32221 slave.cpp:3098] Asked to shut down framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000 by master@172.17.0.2:40041
I0612 18:26:51.902276 32221 slave.cpp:3123] Shutting down framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000
I0612 18:26:51.902292 32221 slave.cpp:5590] Shutting down executor 'default' of framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000 (via HTTP)
I0612 18:26:51.902644 32221 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 165b9039-d920-477a-aacb-f1cc63dbcf69-S0 from framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000
I0612 18:26:51.902786 32221 hierarchical.cpp:353] Removed framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000
I0612 18:26:51.903479 32218 executor.cpp:725] Enqueuing event SHUTDOWN received from http://172.17.0.2:40041/slave(689)/api/v1/executor
I0612 18:26:51.904923 32219 slave.cpp:5268] Executor 'default' of framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000 exited with status 0
I0612 18:26:51.905002 32219 slave.cpp:5368] Cleaning up executor 'default' of framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000 (via HTTP)
W0612 18:26:51.905148 32223 master.cpp:6600] Ignoring unknown exited executor 'default' of framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000 on agent 165b9039-d920-477a-aacb-f1cc63dbcf69-S0 at slave(689)@172.17.0.2:40041 (366b2e5ef4a2)
I0612 18:26:51.906298 32217 gc.cpp:55] Scheduling '/tmp/ContentType_SchedulerTest_TaskRunning_0_48qILt/slaves/165b9039-d920-477a-aacb-f1cc63dbcf69-S0/frameworks/165b9039-d920-477a-aacb-f1cc63dbcf69-0000/executors/default/runs/d4fddc3a-6d55-475e-bd34-a692e8a10d47' for gc 6.99998952393481days in the future
I0612 18:26:51.906379 32219 slave.cpp:5464] Cleaning up framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000
I0612 18:26:51.906476 32218 status_update_manager.cpp:285] Closing status update streams for framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000
I0612 18:26:51.906492 32219 slave.cpp:817] Agent terminating
I0612 18:26:51.906504 32218 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 165b9039-d920-477a-aacb-f1cc63dbcf69-0000
I0612 18:26:51.906715 32217 gc.cpp:55] Scheduling '/tmp/ContentType_SchedulerTest_TaskRunning_0_48qILt/slaves/165b9039-d920-477a-aacb-f1cc63dbcf69-S0/frameworks/165b9039-d920-477a-aacb-f1cc63dbcf69-0000/executors/default' for gc 6.99998950984889days in the future
I0612 18:26:51.907057 32217 gc.cpp:55] Scheduling '/tmp/ContentType_SchedulerTest_TaskRunning_0_48qILt/slaves/165b9039-d920-477a-aacb-f1cc63dbcf69-S0/frameworks/165b9039-d920-477a-aacb-f1cc63dbcf69-0000' for gc 6.99998950851852days in the future
I0612 18:26:51.909497 32219 master.cpp:1314] Agent 165b9039-d920-477a-aacb-f1cc63dbcf69-S0 at slave(689)@172.17.0.2:40041 (366b2e5ef4a2) disconnected
I0612 18:26:51.909540 32219 master.cpp:3237] Disconnecting agent 165b9039-d920-477a-aacb-f1cc63dbcf69-S0 at slave(689)@172.17.0.2:40041 (366b2e5ef4a2)
I0612 18:26:51.909581 32219 master.cpp:3256] Deactivating agent 165b9039-d920-477a-aacb-f1cc63dbcf69-S0 at slave(689)@172.17.0.2:40041 (366b2e5ef4a2)
I0612 18:26:51.909641 32219 master.cpp:1156] Master terminating
I0612 18:26:51.909680 32218 hierarchical.cpp:715] Agent 165b9039-d920-477a-aacb-f1cc63dbcf69-S0 deactivated
I0612 18:26:51.909916 32218 hierarchical.cpp:620] Removed agent 165b9039-d920-477a-aacb-f1cc63dbcf69-S0
[ OK ] ContentType/SchedulerTest.TaskRunning/0 (62 ms)
[ RUN ] ContentType/SchedulerTest.TaskRunning/1
I0612 18:26:51.915057 32215 cluster.cpp:162] Creating default 'local' authorizer
I0612 18:26:51.916538 32218 master.cpp:436] Master 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a (366b2e5ef4a2) started on 172.17.0.2:40041
I0612 18:26:51.916610 32218 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/6Juk8c/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/6Juk8c/master" --zk_session_timeout="10secs"
I0612 18:26:51.916898 32218 master.cpp:488] Master only allowing authenticated frameworks to register
I0612 18:26:51.916937 32218 master.cpp:502] Master only allowing authenticated agents to register
I0612 18:26:51.916972 32218 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0612 18:26:51.917009 32218 credentials.hpp:37] Loading credentials for authentication from '/tmp/6Juk8c/credentials'
I0612 18:26:51.917206 32218 master.cpp:560] Using default 'crammd5' authenticator
I0612 18:26:51.917304 32218 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0612 18:26:51.917387 32218 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0612 18:26:51.917469 32218 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0612 18:26:51.917549 32218 master.cpp:640] Authorization enabled
I0612 18:26:51.917666 32219 whitelist_watcher.cpp:77] No whitelist given
I0612 18:26:51.917842 32223 hierarchical.cpp:169] Initialized hierarchical allocator process
I0612 18:26:51.918912 32216 master.cpp:2159] Elected as the leading master!
I0612 18:26:51.918944 32216 master.cpp:1698] Recovering from registrar
I0612 18:26:51.919306 32216 registrar.cpp:345] Recovering registrar
I0612 18:26:51.919754 32216 registrar.cpp:389] Successfully fetched the registry (0B) in 407040ns
I0612 18:26:51.919819 32216 registrar.cpp:493] Applied 1 operations in 9966ns; attempting to update the registry
I0612 18:26:51.920421 32216 registrar.cpp:550] Successfully updated the registry in 567808ns
I0612 18:26:51.920495 32216 registrar.cpp:422] Successfully recovered registrar
I0612 18:26:51.920939 32219 master.cpp:1797] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0612 18:26:51.921028 32217 hierarchical.cpp:207] Skipping recovery of hierarchical allocator: nothing to recover
I0612 18:26:51.928712 32215 cluster.cpp:448] Creating default 'local' authorizer
I0612 18:26:51.929441 32221 slave.cpp:248] Mesos agent started on (690)@172.17.0.2:40041
I0612 18:26:51.929818 32215 scheduler.cpp:184] Version: 1.4.0
I0612 18:26:51.929474 32221 slave.cpp:249] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/ContentType_SchedulerTest_TaskRunning_1_A8go4z/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/ContentType_SchedulerTest_TaskRunning_1_A8go4z/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/ContentType_SchedulerTest_TaskRunning_1_A8go4z/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_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ContentType_SchedulerTest_TaskRunning_1_A8go4z/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/ContentType_SchedulerTest_TaskRunning_1_A8go4z/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/ContentType_SchedulerTest_TaskRunning_1_A8go4z" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/ContentType_SchedulerTest_TaskRunning_1_jZGlBo"
I0612 18:26:51.929889 32221 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_SchedulerTest_TaskRunning_1_A8go4z/credential'
I0612 18:26:51.930135 32221 slave.cpp:281] Agent using credential for: test-principal
I0612 18:26:51.930155 32221 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_SchedulerTest_TaskRunning_1_A8go4z/http_credentials'
I0612 18:26:51.930364 32221 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0612 18:26:51.930375 32222 scheduler.cpp:470] New master detected at master@172.17.0.2:40041
I0612 18:26:51.930416 32222 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master
I0612 18:26:51.930465 32221 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0612 18:26:51.931180 32221 slave.cpp:552] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0612 18:26:51.931246 32221 slave.cpp:560] Agent attributes: [ ]
I0612 18:26:51.931260 32221 slave.cpp:565] Agent hostname: 366b2e5ef4a2
I0612 18:26:51.931349 32220 status_update_manager.cpp:177] Pausing sending status updates
I0612 18:26:51.931938 32221 state.cpp:62] Recovering state from '/tmp/ContentType_SchedulerTest_TaskRunning_1_jZGlBo/meta'
I0612 18:26:51.932188 32218 status_update_manager.cpp:203] Recovering status update manager
I0612 18:26:51.932438 32221 slave.cpp:6127] Finished recovery
I0612 18:26:51.932781 32223 scheduler.cpp:361] Connected with the master at http://172.17.0.2:40041/master/api/v1/scheduler
I0612 18:26:51.933543 32221 slave.cpp:6309] Querying resource estimator for oversubscribable resources
I0612 18:26:51.933765 32221 slave.cpp:945] New master detected at master@172.17.0.2:40041
I0612 18:26:51.933792 32219 status_update_manager.cpp:177] Pausing sending status updates
I0612 18:26:51.933821 32221 slave.cpp:980] Detecting new master
I0612 18:26:51.933910 32221 slave.cpp:6323] Received oversubscribable resources {} from the resource estimator
I0612 18:26:51.934170 32218 scheduler.cpp:243] Sending SUBSCRIBE call to http://172.17.0.2:40041/master/api/v1/scheduler
I0612 18:26:51.934994 32223 process.cpp:3778] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0612 18:26:51.935712 32222 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:46142
I0612 18:26:51.935946 32222 master.cpp:2511] Received subscription request for HTTP framework 'default'
I0612 18:26:51.935987 32222 master.cpp:2195] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0612 18:26:51.936194 32217 master.cpp:2647] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0612 18:26:51.936523 32222 hierarchical.cpp:301] Added framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000
I0612 18:26:51.936609 32222 hierarchical.cpp:1911] No allocations performed
I0612 18:26:51.936627 32222 hierarchical.cpp:2001] No inverse offers to send out!
I0612 18:26:51.936645 32222 hierarchical.cpp:1493] Performed allocation for 0 agents in 42587ns
I0612 18:26:51.937029 32217 master.hpp:2202] Sending heartbeat to 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000
I0612 18:26:51.937657 32217 slave.cpp:1007] Authenticating with master master@172.17.0.2:40041
I0612 18:26:51.937711 32217 slave.cpp:1018] Using default CRAM-MD5 authenticatee
I0612 18:26:51.937899 32219 authenticatee.cpp:121] Creating new client SASL connection
I0612 18:26:51.938163 32219 master.cpp:7465] Authenticating slave(690)@172.17.0.2:40041
I0612 18:26:51.938376 32216 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1287)@172.17.0.2:40041
I0612 18:26:51.938382 32221 scheduler.cpp:676] Enqueuing event SUBSCRIBED received from http://172.17.0.2:40041/master/api/v1/scheduler
I0612 18:26:51.938522 32219 authenticator.cpp:98] Creating new server SASL connection
I0612 18:26:51.938730 32221 scheduler.cpp:676] Enqueuing event HEARTBEAT received from http://172.17.0.2:40041/master/api/v1/scheduler
I0612 18:26:51.941383 32219 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0612 18:26:51.941423 32219 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0612 18:26:51.941493 32219 authenticator.cpp:204] Received SASL authentication start
I0612 18:26:51.941560 32219 authenticator.cpp:326] Authentication requires more steps
I0612 18:26:51.941603 32219 authenticatee.cpp:259] Received SASL authentication step
I0612 18:26:51.941658 32219 authenticator.cpp:232] Received SASL authentication step
I0612 18:26:51.941684 32219 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '366b2e5ef4a2' server FQDN: '366b2e5ef4a2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0612 18:26:51.941700 32219 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0612 18:26:51.941721 32219 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0612 18:26:51.941741 32219 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '366b2e5ef4a2' server FQDN: '366b2e5ef4a2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0612 18:26:51.941754 32219 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0612 18:26:51.941766 32219 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0612 18:26:51.941789 32219 authenticator.cpp:318] Authentication success
I0612 18:26:51.941861 32219 authenticatee.cpp:299] Authentication success
I0612 18:26:51.941959 32219 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1287)@172.17.0.2:40041
I0612 18:26:51.941947 32221 master.cpp:7495] Successfully authenticated principal 'test-principal' at slave(690)@172.17.0.2:40041
I0612 18:26:51.942134 32221 slave.cpp:1102] Successfully authenticated with master master@172.17.0.2:40041
I0612 18:26:51.942273 32221 slave.cpp:1522] Will retry registration in 17.44203ms if necessary
I0612 18:26:51.942411 32221 master.cpp:5467] Received register agent message from slave(690)@172.17.0.2:40041 (366b2e5ef4a2)
I0612 18:26:51.942487 32221 master.cpp:3699] Authorizing agent with principal 'test-principal'
I0612 18:26:51.942793 32221 master.cpp:5527] Authorized registration of agent at slave(690)@172.17.0.2:40041 (366b2e5ef4a2)
I0612 18:26:51.942873 32221 master.cpp:5606] Registering agent at slave(690)@172.17.0.2:40041 (366b2e5ef4a2) with id 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-S0
I0612 18:26:51.943126 32221 registrar.cpp:493] Applied 1 operations in 37093ns; attempting to update the registry
I0612 18:26:51.943514 32216 registrar.cpp:550] Successfully updated the registry in 350976ns
I0612 18:26:51.943851 32223 master.cpp:5653] Admitted agent 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-S0 at slave(690)@172.17.0.2:40041 (366b2e5ef4a2)
I0612 18:26:51.944175 32219 slave.cpp:4825] Received ping from slave-observer(620)@172.17.0.2:40041
I0612 18:26:51.944180 32223 master.cpp:5684] Registered agent 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-S0 at slave(690)@172.17.0.2:40041 (366b2e5ef4a2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0612 18:26:51.944268 32219 slave.cpp:1148] Registered with master master@172.17.0.2:40041; given agent ID 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-S0
I0612 18:26:51.944316 32222 hierarchical.cpp:587] Added agent 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-S0 (366b2e5ef4a2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0612 18:26:51.944371 32223 status_update_manager.cpp:184] Resuming sending status updates
I0612 18:26:51.944617 32219 slave.cpp:1168] Checkpointing SlaveInfo to '/tmp/ContentType_SchedulerTest_TaskRunning_1_jZGlBo/meta/slaves/9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-S0/slave.info'
I0612 18:26:51.944723 32222 hierarchical.cpp:2001] No inverse offers to send out!
I0612 18:26:51.944744 32222 hierarchical.cpp:1493] Performed allocation for 1 agents in 335705ns
I0612 18:26:51.944926 32219 slave.cpp:1206] Forwarding total oversubscribed resources {}
I0612 18:26:51.945044 32218 master.cpp:7295] Sending 1 offers to framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 (default)
I0612 18:26:51.945741 32218 master.cpp:6338] Received update of agent 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-S0 at slave(690)@172.17.0.2:40041 (366b2e5ef4a2) with total oversubscribed resources {}
I0612 18:26:51.947135 32219 scheduler.cpp:676] Enqueuing event OFFERS received from http://172.17.0.2:40041/master/api/v1/scheduler
I0612 18:26:51.948362 32217 scheduler.cpp:243] Sending ACCEPT call to http://172.17.0.2:40041/master/api/v1/scheduler
I0612 18:26:51.950152 32220 process.cpp:3778] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0612 18:26:51.950752 32218 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:46140
I0612 18:26:51.951789 32218 master.cpp:3914] Processing ACCEPT call for offers: [ 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-O0 ] on agent 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-S0 at slave(690)@172.17.0.2:40041 (366b2e5ef4a2) for framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 (default)
I0612 18:26:51.951918 32218 master.cpp:3466] Authorizing framework principal 'test-principal' to launch task 1
W0612 18:26:51.952917 32222 validation.cpp:1284] Executor 'default' 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 18:26:51.952967 32222 validation.cpp:1296] Executor 'default' 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 18:26:51.953217 32222 master.cpp:9314] Adding task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-S0 at slave(690)@172.17.0.2:40041 (366b2e5ef4a2)
I0612 18:26:51.953388 32222 master.cpp:4569] Launching task 1 of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 (default) with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-S0 at slave(690)@172.17.0.2:40041 (366b2e5ef4a2)
I0612 18:26:51.953717 32223 slave.cpp:1632] Got assigned task '1' for framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000
I0612 18:26:51.954159 32223 slave.cpp:1913] Authorizing task '1' for framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000
I0612 18:26:51.954195 32223 slave.cpp:6626] Authorizing framework principal 'test-principal' to launch task 1
I0612 18:26:51.954907 32223 slave.cpp:2100] Launching task '1' for framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000
I0612 18:26:51.954947 32217 hierarchical.cpp:912] Updated allocation of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 on agent 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-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 18:26:51.955587 32223 paths.cpp:573] Trying to chown '/tmp/ContentType_SchedulerTest_TaskRunning_1_jZGlBo/slaves/9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-S0/frameworks/9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000/executors/default/runs/53574c00-590c-446a-93aa-45e4ef056de4' to user 'mesos'
I0612 18:26:51.955801 32223 slave.cpp:7086] Launching executor 'default' of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 with resources {} in work directory '/tmp/ContentType_SchedulerTest_TaskRunning_1_jZGlBo/slaves/9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-S0/frameworks/9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000/executors/default/runs/53574c00-590c-446a-93aa-45e4ef056de4'
I0612 18:26:51.956073 32223 slave.cpp:2795] Launching container 53574c00-590c-446a-93aa-45e4ef056de4 for executor 'default' of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000
I0612 18:26:51.956288 32223 slave.cpp:2329] Queued task '1' for executor 'default' of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000
I0612 18:26:51.956344 32223 slave.cpp:898] Successfully attached file '/tmp/ContentType_SchedulerTest_TaskRunning_1_jZGlBo/slaves/9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-S0/frameworks/9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000/executors/default/runs/53574c00-590c-446a-93aa-45e4ef056de4'
I0612 18:26:51.956375 32223 slave.cpp:898] Successfully attached file '/tmp/ContentType_SchedulerTest_TaskRunning_1_jZGlBo/slaves/9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-S0/frameworks/9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000/executors/default/runs/53574c00-590c-446a-93aa-45e4ef056de4'
I0612 18:26:51.957579 32218 executor.cpp:192] Version: 1.4.0
I0612 18:26:51.958889 32222 executor.cpp:410] Connected with the agent
I0612 18:26:51.959285 32218 executor.cpp:307] Sending SUBSCRIBE call to http://172.17.0.2:40041/slave(690)/api/v1/executor
I0612 18:26:51.959990 32221 process.cpp:3778] Handling HTTP event for process 'slave(690)' with path: '/slave(690)/api/v1/executor'
I0612 18:26:51.960752 32223 http.cpp:1115] HTTP POST for /slave(690)/api/v1/executor from 172.17.0.2:46144
I0612 18:26:51.960878 32223 slave.cpp:3609] Received Subscribe request for HTTP executor 'default' of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000
I0612 18:26:51.961513 32223 slave.cpp:2542] Sending queued task '1' to executor 'default' of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 (via HTTP)
I0612 18:26:51.962601 32220 executor.cpp:725] Enqueuing event SUBSCRIBED received from http://172.17.0.2:40041/slave(690)/api/v1/executor
I0612 18:26:51.963029 32219 executor.cpp:725] Enqueuing event LAUNCH received from http://172.17.0.2:40041/slave(690)/api/v1/executor
I0612 18:26:51.973582 32223 executor.cpp:307] Sending UPDATE call to http://172.17.0.2:40041/slave(690)/api/v1/executor
I0612 18:26:51.974566 32217 process.cpp:3778] Handling HTTP event for process 'slave(690)' with path: '/slave(690)/api/v1/executor'
I0612 18:26:51.975291 32223 http.cpp:1115] HTTP POST for /slave(690)/api/v1/executor from 172.17.0.2:46146
I0612 18:26:51.975412 32223 slave.cpp:4295] Handling status update TASK_RUNNING (UUID: cf93ca46-cbc8-4a5d-9980-0b518d90733c) for task 1 of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000
I0612 18:26:51.975931 32219 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: cf93ca46-cbc8-4a5d-9980-0b518d90733c) for task 1 of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000
I0612 18:26:51.975980 32219 status_update_manager.cpp:500] Creating StatusUpdate stream for task 1 of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000
I0612 18:26:51.976178 32219 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: cf93ca46-cbc8-4a5d-9980-0b518d90733c) for task 1 of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 to the agent
I0612 18:26:51.976351 32219 slave.cpp:4735] Forwarding the update TASK_RUNNING (UUID: cf93ca46-cbc8-4a5d-9980-0b518d90733c) for task 1 of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 to master@172.17.0.2:40041
I0612 18:26:51.976464 32219 slave.cpp:4629] Status update manager successfully handled status update TASK_RUNNING (UUID: cf93ca46-cbc8-4a5d-9980-0b518d90733c) for task 1 of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000
I0612 18:26:51.976634 32219 master.cpp:6483] Status update TASK_RUNNING (UUID: cf93ca46-cbc8-4a5d-9980-0b518d90733c) for task 1 of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 from agent 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-S0 at slave(690)@172.17.0.2:40041 (366b2e5ef4a2)
I0612 18:26:51.976672 32219 master.cpp:6545] Forwarding status update TASK_RUNNING (UUID: cf93ca46-cbc8-4a5d-9980-0b518d90733c) for task 1 of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000
I0612 18:26:51.977020 32219 master.cpp:8556] Updating the state of task 1 of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0612 18:26:51.977627 32222 executor.cpp:725] Enqueuing event ACKNOWLEDGED received from http://172.17.0.2:40041/slave(690)/api/v1/executor
I0612 18:26:51.978117 32219 scheduler.cpp:676] Enqueuing event UPDATE received from http://172.17.0.2:40041/master/api/v1/scheduler
I0612 18:26:51.979297 32218 master.cpp:1428] Framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 (default) disconnected
I0612 18:26:51.979390 32218 master.cpp:3200] Deactivating framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 (default)
I0612 18:26:51.979468 32218 master.cpp:3177] Disconnecting framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 (default)
I0612 18:26:51.979528 32218 master.cpp:1443] Giving framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 (default) 0ns to failover
I0612 18:26:51.979545 32219 hierarchical.cpp:410] Deactivated framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000
I0612 18:26:51.991650 32216 master.cpp:7136] Framework failover timeout, removing framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 (default)
I0612 18:26:51.991688 32216 master.cpp:7988] Removing framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 (default)
I0612 18:26:51.992010 32216 master.cpp:8556] Updating the state of task 1 of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0612 18:26:51.992478 32216 master.cpp:8650] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 on agent 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-S0 at slave(690)@172.17.0.2:40041 (366b2e5ef4a2)
I0612 18:26:51.992889 32216 master.cpp:8679] Removing executor 'default' with resources {} of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 on agent 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-S0 at slave(690)@172.17.0.2:40041 (366b2e5ef4a2)
I0612 18:26:51.992899 32223 slave.cpp:3098] Asked to shut down framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 by master@172.17.0.2:40041
I0612 18:26:51.992980 32223 slave.cpp:3123] Shutting down framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000
I0612 18:26:51.993000 32223 slave.cpp:5590] Shutting down executor 'default' of framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000 (via HTTP)
I0612 18:26:51.993352 32220 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 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-S0 from framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000
I0612 18:26:51.993602 32220 hierarchical.cpp:353] Removed framework 9fd9ee89-8f0f-42f7-9734-cf124c1a9f9a-0000
I0612 18:26:51.994604 32220 executor.cpp:725] Enqueuing event SHUTDOWN received from http://172.17.0.2:40041/slave(690)/api/v1/executor
*** Aborted at 1497292011 (unix time) try "date -d @1497292011" if you are using GNU date ***
PC: @ 0x1dc3f27 testing::internal::UntypedFunctionMockerBase::SetOwnerAndName()
*** SIGSEGV (@0x0) received by PID 32215 (TID 0x2b76f9531700) from PID 0; stack trace: ***
@ 0x2b76efb6f330 (unknown)
@ 0x1dc3f27 testing::internal::UntypedFunctionMockerBase::SetOwnerAndName()
@ 0xe5233e mesos::internal::tests::executor::TestMesos<>::events()
@ 0xe3f6ed std::_Function_handler<>::_M_invoke()
@ 0x2b76ed0ebaa5 process::AsyncExecutorProcess::execute<>()
@ 0x2b76ed0f6025 _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchI7NothingNS0_20AsyncExecutorProcessERKSt8functionIFvRKSt5queueIN5mesos2v18executor5EventESt5dequeISC_SaISC_EEEEESG_SK_SG_EENS0_6FutureIT_EERKNS0_3PIDIT0_EEMSR_FSO_T1_T2_ET3_T4_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_
@ 0x2b76ee0f7891 process::ProcessManager::resume()
@ 0x2b76ee101537 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x2b76efe2ea60 (unknown)
@ 0x2b76efb67184 start_thread
@ 0x2b76f069abed (unknown)
make[3]: Leaving directory `/mesos/build'
make[3]: *** [CMakeFiles/check] Segmentation fault (core dumped)
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-1497284112-9044
Untagged: mesos-1497284112-9044:latest
Deleted: sha256:70910265cb8bb8f024c9abe607ac709b6de4562910ffe326790d600f58a26a21
Deleted: sha256:342d2a56f6cbb1be127b8a3fa7cdf499e954be285cd8f18308f0ba6dbc450ea2
Deleted: sha256:3eef24056ae7516f685b26282656a5822769618d222e72b2045ff32118774a8c
Deleted: sha256:3916992b4283ef48975ea3554f0baeb1419acae33aedf6abbd2b737131648b1c
Deleted: sha256:b9c06af7d99eb6661b26e5c78a6251f0dfc3611adef61e3f755ccd8b29508f23
Deleted: sha256:3569c329b9ec017e5403ed94c58410a3b31747996ffe76eab75163482f0d0d35
Deleted: sha256:2c6c8189045b09dee5e1a487cab6710aded868a1b5e8670a1181f060f4c2d355
Deleted: sha256:a596113ba8f80a3c10ba44d275e306b556834f6be1da9c1390dc16a7a5d90523
Deleted: sha256:c5a29450c386bb1cdd23173fe3cffc9f09e7ffc78b95f9614dc183161e90d477
Deleted: sha256:2a6a96e8ee8461d848c33b516b19bf4297b91770e152442a2c3c8e86f82f579a
Deleted: sha256:e384a8d1ddb4aa8c3de5c7f12da147f18436f6d5f1facc42b7ddda07cd3fcc7d
Deleted: sha256:fc63db24512db667841c19a8cb351ccf3bfdc9971d0db4a232f10dd6e808bc40
Deleted: sha256:9dcb65c3a3c103f4c4e69b58328c3ceafbee5fac23b1994b7c6a687ba2e20213
Deleted: sha256:621155cc8eea66ee4dd97a7b07fdbbcf3fef12b06ac83d8f3d9c3e8fb2aea474
Deleted: sha256:cc89236256048d7d1d46e84950e45df096c538aba53b8d74dd498825273cf153
Deleted: sha256:407ccbd018677fa4c36d76763b0d739ee341f4825d1c38a109ab7d34bd25b9d9
Deleted: sha256:2982e7c060a7944d1855704f37af28d758c9a713628a61405f7e0f80da093e13
Deleted: sha256:a5ca4f2c4d13259e0e19275914077720572edd4e735d10e0d3a264f1301f28d1
Deleted: sha256:5ca4e550eb99163ee3596477c54c5f7b837eaaf66c22e1e7cb82becffdea171e
Deleted: sha256:1a9a67bcd2a5203d38063b9e41581e1f1f06912abc4b9d731ef78936fe084a84
Deleted: sha256:efe90c3d9ed29c1748b9d33eead51d9404498a2af876e851f0a9f64205823b0b
Deleted: sha256:16cc7027174581ea9b3d034178491b76392c331152f2bbd06df201faf2a59ca8
Deleted: sha256:803f6242580b8ebe153037a5560a1fb414e80d70fbd7d2504f02059b7a55f87f
Deleted: sha256:804f6231c5c3b31680fa1d0d59a2434f87983519c65aa09f95e78e5508f2a67d
Build step 'Execute shell' marked build as failure