See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3567/display/redirect>
------------------------------------------ [...truncated 22.71 MB...] I0426 18:23:36.861888 25371 master.cpp:3199] Disconnecting agent c46be2cf-0f32-4f1f-8773-1d1a872636cf-S0 at slave(670)@172.17.0.2:38480 (9c94607f65f8) I0426 18:23:36.861922 25371 master.cpp:3218] Deactivating agent c46be2cf-0f32-4f1f-8773-1d1a872636cf-S0 at slave(670)@172.17.0.2:38480 (9c94607f65f8) I0426 18:23:36.862036 25367 hierarchical.cpp:655] Agent c46be2cf-0f32-4f1f-8773-1d1a872636cf-S0 deactivated I0426 18:23:36.864684 25376 master.cpp:1157] Master terminating I0426 18:23:36.864991 25377 hierarchical.cpp:560] Removed agent c46be2cf-0f32-4f1f-8773-1d1a872636cf-S0 [ OK ] ContentType/SchedulerTest.TaskGroupRunning/1 (402 ms) [ RUN ] ContentType/SchedulerTest.ReconcileTask/0 I0426 18:23:36.869699 25366 cluster.cpp:162] Creating default 'local' authorizer I0426 18:23:36.870712 25379 master.cpp:438] Master b6cb5af5-64bc-4a8b-8bed-56469e7f316e (9c94607f65f8) started on 172.17.0.2:38480 I0426 18:23:36.870739 25379 master.cpp:440] 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/DK5Ip5/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/DK5Ip5/master" --zk_session_timeout="10secs" I0426 18:23:36.871031 25379 master.cpp:490] Master only allowing authenticated frameworks to register I0426 18:23:36.871047 25379 master.cpp:504] Master only allowing authenticated agents to register I0426 18:23:36.871060 25379 master.cpp:517] Master only allowing authenticated HTTP frameworks to register I0426 18:23:36.871073 25379 credentials.hpp:37] Loading credentials for authentication from '/tmp/DK5Ip5/credentials' I0426 18:23:36.871251 25379 master.cpp:562] Using default 'crammd5' authenticator I0426 18:23:36.871322 25379 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0426 18:23:36.871382 25379 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0426 18:23:36.871474 25379 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0426 18:23:36.873025 25379 master.cpp:642] Authorization enabled I0426 18:23:36.873221 25373 hierarchical.cpp:159] Initialized hierarchical allocator process I0426 18:23:36.873222 25371 whitelist_watcher.cpp:77] No whitelist given I0426 18:23:36.874414 25382 master.cpp:2163] Elected as the leading master! I0426 18:23:36.874436 25382 master.cpp:1702] Recovering from registrar I0426 18:23:36.874549 25370 registrar.cpp:345] Recovering registrar I0426 18:23:36.874871 25368 registrar.cpp:389] Successfully fetched the registry (0B) in 276992ns I0426 18:23:36.874974 25368 registrar.cpp:493] Applied 1 operations in 9235ns; attempting to update the registry I0426 18:23:36.875269 25368 registrar.cpp:550] Successfully updated the registry in 258816ns I0426 18:23:36.875355 25368 registrar.cpp:422] Successfully recovered registrar I0426 18:23:36.875633 25373 hierarchical.cpp:186] Skipping recovery of hierarchical allocator: nothing to recover I0426 18:23:36.875643 25372 master.cpp:1801] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I0426 18:23:36.878407 25366 cluster.cpp:448] Creating default 'local' authorizer I0426 18:23:36.879088 25377 slave.cpp:225] Mesos agent started on (671)@172.17.0.2:38480 I0426 18:23:36.879113 25377 slave.cpp:226] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/ContentType_SchedulerTest_ReconcileTask_0_diR9S4/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io"; --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ContentType_SchedulerTest_ReconcileTask_0_diR9S4/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_ReconcileTask_0_diR9S4/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_ReconcileTask_0_diR9S4" --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_ReconcileTask_0_32yq9z" I0426 18:23:36.879469 25377 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_SchedulerTest_ReconcileTask_0_diR9S4/credential' I0426 18:23:36.879474 25366 scheduler.cpp:184] Version: 1.3.0 I0426 18:23:36.879603 25377 slave.cpp:258] Agent using credential for: test-principal I0426 18:23:36.879621 25377 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_SchedulerTest_ReconcileTask_0_diR9S4/http_credentials' I0426 18:23:36.879784 25377 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0426 18:23:36.879840 25377 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0426 18:23:36.879978 25373 scheduler.cpp:470] New master detected at [email protected]:38480 I0426 18:23:36.880004 25373 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master I0426 18:23:36.880460 25377 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0426 18:23:36.880511 25377 slave.cpp:533] Agent attributes: [ ] I0426 18:23:36.880519 25377 slave.cpp:538] Agent hostname: 9c94607f65f8 I0426 18:23:36.880652 25381 status_update_manager.cpp:177] Pausing sending status updates I0426 18:23:36.881038 25375 state.cpp:62] Recovering state from '/tmp/ContentType_SchedulerTest_ReconcileTask_0_32yq9z/meta' I0426 18:23:36.881683 25371 scheduler.cpp:361] Connected with the master at http://172.17.0.2:38480/master/api/v1/scheduler I0426 18:23:36.881925 25368 status_update_manager.cpp:203] Recovering status update manager I0426 18:23:36.882477 25374 slave.cpp:5963] Finished recovery I0426 18:23:36.882664 25378 scheduler.cpp:243] Sending SUBSCRIBE call to http://172.17.0.2:38480/master/api/v1/scheduler I0426 18:23:36.883083 25374 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0426 18:23:36.883322 25378 process.cpp:3722] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0426 18:23:36.883514 25377 status_update_manager.cpp:177] Pausing sending status updates I0426 18:23:36.883510 25374 slave.cpp:918] New master detected at [email protected]:38480 I0426 18:23:36.883581 25374 slave.cpp:953] Detecting new master I0426 18:23:36.883741 25374 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0426 18:23:36.884016 25369 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:50458 I0426 18:23:36.884133 25369 master.cpp:2515] Received subscription request for HTTP framework 'default' I0426 18:23:36.884171 25369 master.cpp:2199] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' I0426 18:23:36.884351 25369 master.cpp:2630] Subscribing framework 'default' with checkpointing disabled and capabilities [ ] I0426 18:23:36.884582 25382 hierarchical.cpp:271] Added framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 I0426 18:23:36.884599 25376 master.hpp:2167] Sending heartbeat to b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 I0426 18:23:36.884635 25382 hierarchical.cpp:1862] No allocations performed I0426 18:23:36.884654 25382 hierarchical.cpp:1952] No inverse offers to send out! I0426 18:23:36.884673 25382 hierarchical.cpp:1446] Performed allocation for 0 agents in 46550ns I0426 18:23:36.885161 25368 scheduler.cpp:676] Enqueuing event SUBSCRIBED received from http://172.17.0.2:38480/master/api/v1/scheduler I0426 18:23:36.885443 25368 scheduler.cpp:676] Enqueuing event HEARTBEAT received from http://172.17.0.2:38480/master/api/v1/scheduler I0426 18:23:36.886198 25374 slave.cpp:980] Authenticating with master [email protected]:38480 I0426 18:23:36.886261 25374 slave.cpp:991] Using default CRAM-MD5 authenticatee I0426 18:23:36.886369 25378 authenticatee.cpp:121] Creating new client SASL connection I0426 18:23:36.886566 25374 master.cpp:7257] Authenticating slave(671)@172.17.0.2:38480 I0426 18:23:36.886677 25378 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1254)@172.17.0.2:38480 I0426 18:23:36.886785 25372 authenticator.cpp:98] Creating new server SASL connection I0426 18:23:36.886998 25380 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0426 18:23:36.887027 25380 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0426 18:23:36.887104 25376 authenticator.cpp:204] Received SASL authentication start I0426 18:23:36.887166 25376 authenticator.cpp:326] Authentication requires more steps I0426 18:23:36.887224 25376 authenticatee.cpp:259] Received SASL authentication step I0426 18:23:36.887364 25377 authenticator.cpp:232] Received SASL authentication step I0426 18:23:36.887405 25377 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9c94607f65f8' server FQDN: '9c94607f65f8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0426 18:23:36.887424 25377 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0426 18:23:36.887449 25377 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0426 18:23:36.887468 25377 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9c94607f65f8' server FQDN: '9c94607f65f8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0426 18:23:36.887485 25377 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0426 18:23:36.887496 25377 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0426 18:23:36.887563 25377 authenticator.cpp:318] Authentication success I0426 18:23:36.887648 25373 authenticatee.cpp:299] Authentication success I0426 18:23:36.887667 25376 master.cpp:7287] Successfully authenticated principal 'test-principal' at slave(671)@172.17.0.2:38480 I0426 18:23:36.887703 25371 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1254)@172.17.0.2:38480 I0426 18:23:36.887737 25373 slave.cpp:1075] Successfully authenticated with master [email protected]:38480 I0426 18:23:36.887923 25373 slave.cpp:1503] Will retry registration in 18.369582ms if necessary I0426 18:23:36.888008 25377 master.cpp:5447] Registering agent at slave(671)@172.17.0.2:38480 (9c94607f65f8) with id b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0 I0426 18:23:36.888164 25376 registrar.cpp:493] Applied 1 operations in 22046ns; attempting to update the registry I0426 18:23:36.888443 25370 registrar.cpp:550] Successfully updated the registry in 250112ns I0426 18:23:36.888773 25373 slave.cpp:4745] Received ping from slave-observer(605)@172.17.0.2:38480 I0426 18:23:36.888895 25371 slave.cpp:1121] Registered with master [email protected]:38480; given agent ID b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0 I0426 18:23:36.888911 25371 fetcher.cpp:94] Clearing fetcher cache I0426 18:23:36.888876 25367 master.cpp:5521] Registered agent b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0 at slave(671)@172.17.0.2:38480 (9c94607f65f8) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0426 18:23:36.888984 25374 hierarchical.cpp:527] Added agent b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0 (9c94607f65f8) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0426 18:23:36.889024 25370 status_update_manager.cpp:184] Resuming sending status updates I0426 18:23:36.889241 25371 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/ContentType_SchedulerTest_ReconcileTask_0_32yq9z/meta/slaves/b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0/slave.info' I0426 18:23:36.889371 25374 hierarchical.cpp:1952] No inverse offers to send out! I0426 18:23:36.889395 25374 hierarchical.cpp:1446] Performed allocation for 1 agents in 340844ns I0426 18:23:36.889519 25371 slave.cpp:1187] Forwarding total oversubscribed resources {} I0426 18:23:36.889542 25382 master.cpp:7087] Sending 1 offers to framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 (default) I0426 18:23:36.889775 25382 master.cpp:6106] Received update of agent b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0 at slave(671)@172.17.0.2:38480 (9c94607f65f8) with total oversubscribed resources {} I0426 18:23:36.890282 25374 scheduler.cpp:676] Enqueuing event OFFERS received from http://172.17.0.2:38480/master/api/v1/scheduler I0426 18:23:36.891413 25376 scheduler.cpp:243] Sending ACCEPT call to http://172.17.0.2:38480/master/api/v1/scheduler I0426 18:23:36.891938 25370 process.cpp:3722] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0426 18:23:36.892252 25370 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:50457 I0426 18:23:36.892470 25370 master.cpp:3853] Processing ACCEPT call for offers: [ b6cb5af5-64bc-4a8b-8bed-56469e7f316e-O0 ] on agent b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0 at slave(671)@172.17.0.2:38480 (9c94607f65f8) for framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 (default) I0426 18:23:36.892511 25370 master.cpp:3429] Authorizing framework principal 'test-principal' to launch task 36fac8d6-599f-40ec-9d22-fa20f75e15fa W0426 18:23:36.893158 25370 validation.cpp:1113] Executor 'default' for task '36fac8d6-599f-40ec-9d22-fa20f75e15fa' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W0426 18:23:36.893182 25370 validation.cpp:1125] Executor 'default' for task '36fac8d6-599f-40ec-9d22-fa20f75e15fa' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I0426 18:23:36.893307 25370 master.cpp:9102] Adding task 36fac8d6-599f-40ec-9d22-fa20f75e15fa with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0 at slave(671)@172.17.0.2:38480 (9c94607f65f8) I0426 18:23:36.893409 25370 master.cpp:4503] Launching task 36fac8d6-599f-40ec-9d22-fa20f75e15fa of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 (default) with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0 at slave(671)@172.17.0.2:38480 (9c94607f65f8) I0426 18:23:36.893776 25370 slave.cpp:1613] Got assigned task '36fac8d6-599f-40ec-9d22-fa20f75e15fa' for framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 I0426 18:23:36.894017 25370 slave.cpp:1894] Authorizing task '36fac8d6-599f-40ec-9d22-fa20f75e15fa' for framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 I0426 18:23:36.894043 25370 slave.cpp:6582] Authorizing framework principal 'test-principal' to launch task 36fac8d6-599f-40ec-9d22-fa20f75e15fa I0426 18:23:36.894522 25370 hierarchical.cpp:852] Updated allocation of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 on agent b6cb5af5-64bc-4a8b-8bed-56469e7f316e-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] I0426 18:23:36.894771 25370 slave.cpp:2081] Launching task '36fac8d6-599f-40ec-9d22-fa20f75e15fa' for framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 I0426 18:23:36.895339 25370 paths.cpp:556] Trying to chown '/tmp/ContentType_SchedulerTest_ReconcileTask_0_32yq9z/slaves/b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0/frameworks/b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000/executors/default/runs/1937f5c1-11b6-4c0b-978a-36a06799b2f8' to user 'mesos' I0426 18:23:36.895503 25370 slave.cpp:6926] Launching executor 'default' of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 with resources {} in work directory '/tmp/ContentType_SchedulerTest_ReconcileTask_0_32yq9z/slaves/b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0/frameworks/b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000/executors/default/runs/1937f5c1-11b6-4c0b-978a-36a06799b2f8' I0426 18:23:36.895786 25370 slave.cpp:2310] Queued task '36fac8d6-599f-40ec-9d22-fa20f75e15fa' for executor 'default' of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 I0426 18:23:36.895833 25370 slave.cpp:871] Successfully attached file '/tmp/ContentType_SchedulerTest_ReconcileTask_0_32yq9z/slaves/b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0/frameworks/b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000/executors/default/runs/1937f5c1-11b6-4c0b-978a-36a06799b2f8' I0426 18:23:36.896560 25376 executor.cpp:192] Version: 1.3.0 I0426 18:23:36.897249 25368 executor.cpp:410] Connected with the agent I0426 18:23:36.897519 25374 executor.cpp:307] Sending SUBSCRIBE call to http://172.17.0.2:38480/slave(671)/api/v1/executor I0426 18:23:36.898028 25377 process.cpp:3722] Handling HTTP event for process 'slave(671)' with path: '/slave(671)/api/v1/executor' I0426 18:23:36.898422 25371 http.cpp:1115] HTTP POST for /slave(671)/api/v1/executor from 172.17.0.2:50463 I0426 18:23:36.898516 25371 slave.cpp:3568] Received Subscribe request for HTTP executor 'default' of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 I0426 18:23:36.898979 25371 slave.cpp:2523] Sending queued task '36fac8d6-599f-40ec-9d22-fa20f75e15fa' to executor 'default' of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 (via HTTP) I0426 18:23:36.899695 25376 executor.cpp:725] Enqueuing event SUBSCRIBED received from http://172.17.0.2:38480/slave(671)/api/v1/executor I0426 18:23:36.899955 25376 executor.cpp:725] Enqueuing event LAUNCH received from http://172.17.0.2:38480/slave(671)/api/v1/executor I0426 18:23:36.900311 25371 executor.cpp:307] Sending UPDATE call to http://172.17.0.2:38480/slave(671)/api/v1/executor I0426 18:23:36.900727 25371 process.cpp:3722] Handling HTTP event for process 'slave(671)' with path: '/slave(671)/api/v1/executor' I0426 18:23:36.901085 25374 http.cpp:1115] HTTP POST for /slave(671)/api/v1/executor from 172.17.0.2:50464 I0426 18:23:36.901154 25374 slave.cpp:4215] Handling status update TASK_RUNNING (UUID: 4d4e5379-8f0d-4795-ace0-f0e978314616) for task 36fac8d6-599f-40ec-9d22-fa20f75e15fa of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 I0426 18:23:36.901439 25381 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 4d4e5379-8f0d-4795-ace0-f0e978314616) for task 36fac8d6-599f-40ec-9d22-fa20f75e15fa of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 I0426 18:23:36.901459 25381 status_update_manager.cpp:500] Creating StatusUpdate stream for task 36fac8d6-599f-40ec-9d22-fa20f75e15fa of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 I0426 18:23:36.901638 25381 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 4d4e5379-8f0d-4795-ace0-f0e978314616) for task 36fac8d6-599f-40ec-9d22-fa20f75e15fa of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 to the agent I0426 18:23:36.901856 25375 slave.cpp:4655] Forwarding the update TASK_RUNNING (UUID: 4d4e5379-8f0d-4795-ace0-f0e978314616) for task 36fac8d6-599f-40ec-9d22-fa20f75e15fa of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 to [email protected]:38480 I0426 18:23:36.901983 25375 slave.cpp:4549] Status update manager successfully handled status update TASK_RUNNING (UUID: 4d4e5379-8f0d-4795-ace0-f0e978314616) for task 36fac8d6-599f-40ec-9d22-fa20f75e15fa of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 I0426 18:23:36.902026 25376 master.cpp:6251] Status update TASK_RUNNING (UUID: 4d4e5379-8f0d-4795-ace0-f0e978314616) for task 36fac8d6-599f-40ec-9d22-fa20f75e15fa of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 from agent b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0 at slave(671)@172.17.0.2:38480 (9c94607f65f8) I0426 18:23:36.902052 25376 master.cpp:6319] Forwarding status update TASK_RUNNING (UUID: 4d4e5379-8f0d-4795-ace0-f0e978314616) for task 36fac8d6-599f-40ec-9d22-fa20f75e15fa of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 I0426 18:23:36.902197 25376 master.cpp:8350] Updating the state of task 36fac8d6-599f-40ec-9d22-fa20f75e15fa of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I0426 18:23:36.902320 25376 executor.cpp:725] Enqueuing event ACKNOWLEDGED received from http://172.17.0.2:38480/slave(671)/api/v1/executor I0426 18:23:36.902545 25376 scheduler.cpp:676] Enqueuing event UPDATE received from http://172.17.0.2:38480/master/api/v1/scheduler I0426 18:23:36.903141 25379 scheduler.cpp:243] Sending RECONCILE call to http://172.17.0.2:38480/master/api/v1/scheduler I0426 18:23:36.903820 25380 process.cpp:3722] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0426 18:23:36.943755 25375 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:50457 I0426 18:23:36.943878 25375 master.cpp:6772] Performing explicit task state reconciliation for 1 tasks of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 (default) I0426 18:23:36.943980 25375 master.cpp:6904] Sending explicit reconciliation state TASK_RUNNING for task 36fac8d6-599f-40ec-9d22-fa20f75e15fa of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 (default) I0426 18:23:36.944576 25380 scheduler.cpp:676] Enqueuing event UPDATE received from http://172.17.0.2:38480/master/api/v1/scheduler I0426 18:23:36.945529 25381 master.cpp:1432] Framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 (default) disconnected I0426 18:23:36.945545 25381 master.cpp:3162] Deactivating framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 (default) I0426 18:23:36.945569 25381 master.cpp:3139] Disconnecting framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 (default) I0426 18:23:36.945586 25381 master.cpp:1447] Giving framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 (default) 0ns to failover I0426 18:23:36.945603 25382 hierarchical.cpp:376] Deactivated framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 I0426 18:23:36.946751 25381 master.cpp:6928] Framework failover timeout, removing framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 (default) I0426 18:23:36.946770 25381 master.cpp:7782] Removing framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 (default) I0426 18:23:36.946825 25381 master.cpp:8350] Updating the state of task 36fac8d6-599f-40ec-9d22-fa20f75e15fa of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I0426 18:23:36.946846 25382 slave.cpp:3057] Asked to shut down framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 by [email protected]:38480 I0426 18:23:36.946871 25382 slave.cpp:3082] Shutting down framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 I0426 18:23:36.946892 25382 slave.cpp:5475] Shutting down executor 'default' of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 (via HTTP) I0426 18:23:36.947099 25381 master.cpp:8444] Removing task 36fac8d6-599f-40ec-9d22-fa20f75e15fa with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 on agent b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0 at slave(671)@172.17.0.2:38480 (9c94607f65f8) I0426 18:23:36.947263 25381 master.cpp:8473] Removing executor 'default' with resources {} of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 on agent b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0 at slave(671)@172.17.0.2:38480 (9c94607f65f8) I0426 18:23:36.947331 25382 hierarchical.cpp:1116] 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 b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0 from framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 I0426 18:23:36.947486 25382 hierarchical.cpp:323] Removed framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 I0426 18:23:36.947825 25380 executor.cpp:725] Enqueuing event SHUTDOWN received from http://172.17.0.2:38480/slave(671)/api/v1/executor I0426 18:23:36.948320 25371 slave.cpp:5161] Executor 'default' of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 exited with status 0 I0426 18:23:36.948406 25371 slave.cpp:5261] Cleaning up executor 'default' of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 (via HTTP) W0426 18:23:36.948448 25379 master.cpp:6374] Ignoring unknown exited executor 'default' of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 on agent b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0 at slave(671)@172.17.0.2:38480 (9c94607f65f8) *** Aborted at 1493231016 (unix time) try "date -d @1493231016" if you are using GNU date *** I0426 18:23:36.948554 25367 gc.cpp:55] Scheduling '/tmp/ContentType_SchedulerTest_ReconcileTask_0_32yq9z/slaves/b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0/frameworks/b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000/executors/default/runs/1937f5c1-11b6-4c0b-978a-36a06799b2f8' for gc 6.99998902179259days in the future I0426 18:23:36.948626 25371 slave.cpp:5349] Cleaning up framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 I0426 18:23:36.948673 25368 gc.cpp:55] Scheduling '/tmp/ContentType_SchedulerTest_ReconcileTask_0_32yq9z/slaves/b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0/frameworks/b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000/executors/default' for gc 6.99998902081185days in the future I0426 18:23:36.948716 25368 status_update_manager.cpp:285] Closing status update streams for framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 I0426 18:23:36.948731 25368 status_update_manager.cpp:531] Cleaning up status update stream for task 36fac8d6-599f-40ec-9d22-fa20f75e15fa of framework b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000 I0426 18:23:36.948743 25371 gc.cpp:55] Scheduling '/tmp/ContentType_SchedulerTest_ReconcileTask_0_32yq9z/slaves/b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0/frameworks/b6cb5af5-64bc-4a8b-8bed-56469e7f316e-0000' for gc 6.99998901961778days in the future I0426 18:23:36.949184 25366 slave.cpp:790] Agent terminating I0426 18:23:36.949306 25371 master.cpp:1315] Agent b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0 at slave(671)@172.17.0.2:38480 (9c94607f65f8) disconnected I0426 18:23:36.949326 25371 master.cpp:3199] Disconnecting agent b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0 at slave(671)@172.17.0.2:38480 (9c94607f65f8) I0426 18:23:36.949352 25371 master.cpp:3218] Deactivating agent b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0 at slave(671)@172.17.0.2:38480 (9c94607f65f8) PC: @ 0x1c8e6c7 testing::internal::UntypedFunctionMockerBase::UntypedInvokeWith() I*** SIGSEGV (@0x28) received by PID 25366 (TID 0x2b6798b63700) from PID 40; stack trace: *** 0426 18:23:36.949834 25373 hierarchical.cpp:655] Agent b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0 deactivated @ 0x2b678fba0330 (unknown) I0426 18:23:36.950772 25366 master.cpp:1157] Master terminating @ 0x1c8e6c7 testing::internal::UntypedFunctionMockerBase::UntypedInvokeWith() I0426 18:23:36.951068 25381 hierarchical.cpp:560] Removed agent b6cb5af5-64bc-4a8b-8bed-56469e7f316e-S0 @ 0xdcda70 mesos::internal::tests::executor::MockHTTPExecutor<>::event() @ 0xdf1907 mesos::internal::tests::executor::TestMesos<>::events() @ 0xddd7fd std::_Function_handler<>::_M_invoke() [ OK ] ContentType/SchedulerTest.ReconcileTask/0 (85 ms) [ RUN ] ContentType/SchedulerTest.ReconcileTask/1 @ 0x2b678d3d8655 process::AsyncExecutorProcess::execute<>() I0426 18:23:36.956995 25366 cluster.cpp:162] Creating default 'local' authorizer I0426 18:23:36.958091 25368 master.cpp:438] Master 61a74772-f1e8-423f-87a7-c7a3a8fe3fcc (9c94607f65f8) started on 172.17.0.2:38480 @ 0x2b678d3e2235 _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_ I0426 18:23:36.958115 25368 master.cpp:440] 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/JiH1lz/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/JiH1lz/master" --zk_session_timeout="10secs" I0426 18:23:36.958283 25368 master.cpp:490] Master only allowing authenticated frameworks to register I0426 18:23:36.958292 25368 master.cpp:504] Master only allowing authenticated agents to register I0426 18:23:36.958297 25368 master.cpp:517] Master only allowing authenticated HTTP frameworks to register I0426 18:23:36.958303 25368 credentials.hpp:37] Loading credentials for authentication from '/tmp/JiH1lz/credentials' I0426 18:23:36.958438 25368 master.cpp:562] Using default 'crammd5' authenticator I0426 18:23:36.958490 25368 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' @ 0x2b678e3bb1f1 process::ProcessManager::resume() I0426 18:23:36.958546 25368 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' @ 0x2b678e3c4077 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv @ 0x2b678fe5fa60 (unknown) I0426 18:23:36.958858 25368 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' @ 0x2b678fb98184 start_thread I0426 18:23:36.958981 25368 master.cpp:642] Authorization enabled @ 0x2b67906cbbed (unknown) make[3]: Leaving directory `/mesos/build' make[3]: *** [CMakeFiles/check] Segmentation fault 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-1493228007-9503 Untagged: mesos-1493228007-9503:latest Deleted: sha256:e4cb1e01f324ddaf315d1240199817bb9f23b8176b9cca87326ab646220c08e4 Deleted: sha256:bfd58759abb897a2dcab04d44bdba10ad56b72c5f1a2e546a460206af9c7c380 Deleted: sha256:847d5790b74bb4f071d69c3f35c76e8c53eaceb52c269d1a7c0f2e002d5bbab9 Deleted: sha256:62f4f4e1d7c01c0fed1471b1464910a48e037874cde4df45c5feb001b2309d0e Deleted: sha256:abb1e967fd44eae0e83ed688c268af176a86335fe1f732accdd175970d5a09d8 Deleted: sha256:1fae0a5f58e05362fe1ce6d9f7e523c71239431227906771dc499c8ac612abf3 Deleted: sha256:7e7f0800751ef42cf326abb7acb7c5bb39c85f3b9ad5a2469260dcf67b6b0b4e Deleted: sha256:2388b3caa525d6a68e763639fe22e94046651dcf301797cd6c92dca060e34f41 Deleted: sha256:abe79bd6eb8828aa89c0b514bf98044d200a8e76b784f143a4f905c22f65afe9 Deleted: sha256:2e5d864882e709ee041df764112b21d778d23adbf665347e43c3b186a6a2e23d Deleted: sha256:89a50b16beb0277f52ccebe22605deae2fa96d2dc827227338ba5a0eeb9e204e Deleted: sha256:39296776653d9a8d93f8855bde611f38e0af8c72fe30eb222c07c291acd910fc Deleted: sha256:b661920347f6b4c09ec04d02c797b9b1bef99feff5ccccf90a3468e1de93ea26 Deleted: sha256:ff76b0696455826365405e3668e7229a70dd987ebb8577ce6ea1b231a64748ba Deleted: sha256:d1fa384bdee89c3736d68df134fd73010ba864cfede815622cbb808d89af0558 Deleted: sha256:59c232e018e50be4bab452fa98d65e05c4e67016288f4b8174e194cbdbcc48c2 Deleted: sha256:51420bc74b90346d4616743835e66a854c771f30ae618db539e9ad85e2bb1e17 Deleted: sha256:7d5e3c373d5991d4f2c4c779f76af84e36c0ad18e01f8f71f5a019e5c83dd784 Deleted: sha256:b17f6d943fa565db9e68b372080b5fac4e1e2f9380e9c588c1eec40eabbd4a0b Deleted: sha256:3865a4dfab1642eb5891c59063e95b1324d126bd31fa1ba21558153411c18674 Deleted: sha256:af994eb0b512d028d17c2ccfb69decff36242217f03bc7c6b7954896325e42ac Deleted: sha256:efaab4e165e715ec6ecaf6e3ad28872b26f04f2440cf6fb0c52772403b53a79c Deleted: sha256:f52d0765ead0a77731ef4c57a68e54460b5648f5f0b388ecbe3e3f3172125a09 Deleted: sha256:dc5901dfda1bfeeaf6e3a0848047d0432c3543a4b5f7601614efded076cc34b9 Build step 'Execute shell' marked build as failure
