See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4452/display/redirect>
------------------------------------------ [...truncated 27.82 MB...] 3: I1114 02:53:06.946594 19961 status_update_manager.cpp:177] Pausing sending status updates 3: I1114 02:53:06.948072 19964 state.cpp:64] Recovering state from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_AgentEndpoint_1_f4uH3U/meta' 3: I1114 02:53:06.948371 19956 status_update_manager.cpp:203] Recovering status update manager 3: I1114 02:53:06.948536 19949 containerizer.cpp:662] Recovering containerizer 3: I1114 02:53:06.950001 19955 provisioner.cpp:416] Provisioner recovery complete 3: I1114 02:53:06.950423 19953 slave.cpp:6432] Finished recovery 3: I1114 02:53:06.951320 19956 status_update_manager.cpp:177] Pausing sending status updates 3: I1114 02:53:06.951333 19954 slave.cpp:1007] New master detected at [email protected]:33432 3: I1114 02:53:06.951396 19954 slave.cpp:1042] Detecting new master 3: I1114 02:53:06.955152 19962 process.cpp:3925] Handling HTTP event for process 'slave(750)' with path: '/slave(750)/api/v1/resource_provider' 3: I1114 02:53:06.957485 19961 http.cpp:1185] HTTP POST for /slave(750)/api/v1/resource_provider from 172.17.0.2:58848 3: I1114 02:53:06.958020 19947 manager.cpp:381] Subscribing resource provider {"name":"test","type":"org.apache.mesos.rp.test"} 3: I1114 02:53:06.964301 19945 slave.cpp:883] Agent terminating 3: I1114 02:53:06.970345 19945 master.cpp:1151] Master terminating 3: [ OK ] ContentType/ResourceProviderManagerHttpApiTest.AgentEndpoint/1 (53 ms) 3: [ RUN ] ContentType/ResourceProviderManagerHttpApiTest.ConvertResources/0 3: I1114 02:53:06.977787 19945 cluster.cpp:162] Creating default 'local' authorizer 3: I1114 02:53:06.980988 19969 master.cpp:448] Master 56cf5864-f4d1-40e5-90da-a37eaf7646c6 (78aba094e6f9) started on 172.17.0.2:33432 3: I1114 02:53:06.981014 19969 master.cpp:450] 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/EoWqJ5/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/EoWqJ5/master" --zk_session_timeout="10secs" 3: I1114 02:53:06.981478 19969 master.cpp:499] Master only allowing authenticated frameworks to register 3: I1114 02:53:06.981488 19969 master.cpp:505] Master only allowing authenticated agents to register 3: I1114 02:53:06.981494 19969 master.cpp:511] Master only allowing authenticated HTTP frameworks to register 3: I1114 02:53:06.981501 19969 credentials.hpp:37] Loading credentials for authentication from '/tmp/EoWqJ5/credentials' 3: I1114 02:53:06.981861 19969 master.cpp:555] Using default 'crammd5' authenticator 3: I1114 02:53:06.982045 19969 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1114 02:53:06.982215 19969 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1114 02:53:06.982373 19969 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1114 02:53:06.982528 19969 master.cpp:634] Authorization enabled 3: I1114 02:53:06.982709 19950 hierarchical.cpp:171] Initialized hierarchical allocator process 3: I1114 02:53:06.982746 19960 whitelist_watcher.cpp:77] No whitelist given 3: I1114 02:53:06.986054 19963 master.cpp:2213] Elected as the leading master! 3: I1114 02:53:06.986091 19963 master.cpp:1693] Recovering from registrar 3: I1114 02:53:06.986253 19955 registrar.cpp:347] Recovering registrar 3: I1114 02:53:06.986997 19955 registrar.cpp:391] Successfully fetched the registry (0B) in 683776ns 3: I1114 02:53:06.987125 19955 registrar.cpp:495] Applied 1 operations in 32640ns; attempting to update the registry 3: I1114 02:53:06.987735 19955 registrar.cpp:552] Successfully updated the registry in 552192ns 3: I1114 02:53:06.987848 19955 registrar.cpp:424] Successfully recovered registrar 3: I1114 02:53:06.988415 19946 master.cpp:1806] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register 3: I1114 02:53:06.988430 19965 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover 3: W1114 02:53:06.993947 19945 process.cpp:3178] Attempted to spawn already running process [email protected]:33432 3: I1114 02:53:06.994899 19945 containerizer.cpp:301] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } 3: W1114 02:53:06.995415 19945 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges 3: W1114 02:53:06.995530 19945 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges 3: I1114 02:53:06.995563 19945 provisioner.cpp:255] Using default backend 'copy' 3: I1114 02:53:06.997514 19945 cluster.cpp:448] Creating default 'local' authorizer 3: I1114 02:53:06.999739 19946 slave.cpp:262] Mesos agent started on (751)@172.17.0.2:33432 3: I1114 02:53:06.999754 19946 slave.cpp:263] Flags at startup: --acls="" --agent_features="capabilities { 3: type: MULTI_ROLE 3: } 3: capabilities { 3: type: HIERARCHICAL_ROLE 3: } 3: capabilities { 3: type: RESERVATION_REFINEMENT 3: } 3: capabilities { 3: type: RESOURCE_PROVIDER 3: } 3: " --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_Six4Gx/store/appc" --authenticate_http_executors="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="false" --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_ResourceProviderManagerHttpApiTest_ConvertResources_0_Six4Gx/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --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_ResourceProviderManagerHttpApiTest_ConvertResources_0_Six4Gx/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/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_Six4Gx/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_Six4Gx/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_ResourceProviderManagerHttpApiTest_ConvertResources_0_Six4Gx/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_ResourceProviderManagerHttpApiTest_ConvertResources_0_Six4Gx" --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_ResourceProviderManagerHttpApiTest_ConvertResources_0_b5SeIO" --zk_session_timeout="10secs" 3: I1114 02:53:07.000257 19946 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_Six4Gx/credential' 3: I1114 02:53:07.000419 19946 slave.cpp:295] Agent using credential for: test-principal 3: I1114 02:53:07.000437 19946 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_Six4Gx/http_credentials' 3: I1114 02:53:07.000725 19946 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' 3: I1114 02:53:07.000855 19946 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' 3: I1114 02:53:07.001065 19946 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1114 02:53:07.001165 19946 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1114 02:53:07.002676 19946 slave.cpp:593] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] 3: I1114 02:53:07.002900 19946 slave.cpp:601] Agent attributes: [ ] 3: I1114 02:53:07.002910 19946 slave.cpp:610] Agent hostname: 78aba094e6f9 3: I1114 02:53:07.003098 19953 status_update_manager.cpp:177] Pausing sending status updates 3: I1114 02:53:07.004681 19954 state.cpp:64] Recovering state from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_b5SeIO/meta' 3: I1114 02:53:07.004995 19954 status_update_manager.cpp:203] Recovering status update manager 3: I1114 02:53:07.005203 19954 containerizer.cpp:662] Recovering containerizer 3: I1114 02:53:07.006691 19962 provisioner.cpp:416] Provisioner recovery complete 3: I1114 02:53:07.007083 19951 slave.cpp:6432] Finished recovery 3: I1114 02:53:07.007786 19946 status_update_manager.cpp:177] Pausing sending status updates 3: I1114 02:53:07.007787 19955 slave.cpp:1007] New master detected at [email protected]:33432 3: I1114 02:53:07.007875 19955 slave.cpp:1042] Detecting new master 3: I1114 02:53:07.014212 19958 slave.cpp:1069] Authenticating with master [email protected]:33432 3: I1114 02:53:07.014333 19958 slave.cpp:1078] Using default CRAM-MD5 authenticatee 3: I1114 02:53:07.014644 19959 authenticatee.cpp:121] Creating new client SASL connection 3: I1114 02:53:07.015040 19967 master.cpp:8285] Authenticating slave(751)@172.17.0.2:33432 3: I1114 02:53:07.015202 19949 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1383)@172.17.0.2:33432 3: I1114 02:53:07.015475 19966 authenticator.cpp:98] Creating new server SASL connection 3: I1114 02:53:07.015750 19969 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1114 02:53:07.015779 19969 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1114 02:53:07.015897 19950 authenticator.cpp:204] Received SASL authentication start 3: I1114 02:53:07.015971 19950 authenticator.cpp:326] Authentication requires more steps 3: I1114 02:53:07.016119 19948 authenticatee.cpp:259] Received SASL authentication step 3: I1114 02:53:07.016261 19952 authenticator.cpp:232] Received SASL authentication step 3: I1114 02:53:07.016293 19952 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '78aba094e6f9' server FQDN: '78aba094e6f9' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1114 02:53:07.016306 19952 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1114 02:53:07.016355 19952 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1114 02:53:07.016372 19952 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '78aba094e6f9' server FQDN: '78aba094e6f9' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1114 02:53:07.016379 19952 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1114 02:53:07.016384 19952 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1114 02:53:07.016396 19952 authenticator.cpp:318] Authentication success 3: I1114 02:53:07.016487 19960 authenticatee.cpp:299] Authentication success 3: I1114 02:53:07.016592 19954 master.cpp:8315] Successfully authenticated principal 'test-principal' at slave(751)@172.17.0.2:33432 3: I1114 02:53:07.016675 19968 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1383)@172.17.0.2:33432 3: I1114 02:53:07.016803 19963 slave.cpp:1161] Successfully authenticated with master [email protected]:33432 3: I1114 02:53:07.017132 19963 slave.cpp:1682] Will retry registration in 18.870593ms if necessary 3: I1114 02:53:07.017354 19953 master.cpp:6032] Received register agent message from slave(751)@172.17.0.2:33432 (78aba094e6f9) 3: I1114 02:53:07.017491 19953 master.cpp:3870] Authorizing agent with principal 'test-principal' 3: I1114 02:53:07.018110 19957 master.cpp:6092] Authorized registration of agent at slave(751)@172.17.0.2:33432 (78aba094e6f9) 3: I1114 02:53:07.018277 19957 master.cpp:6185] Registering agent at slave(751)@172.17.0.2:33432 (78aba094e6f9) with id 56cf5864-f4d1-40e5-90da-a37eaf7646c6-S0 3: I1114 02:53:07.018782 19964 registrar.cpp:495] Applied 1 operations in 67922ns; attempting to update the registry 3: I1114 02:53:07.019464 19964 registrar.cpp:552] Successfully updated the registry in 613888ns 3: I1114 02:53:07.019686 19959 master.cpp:6232] Admitted agent 56cf5864-f4d1-40e5-90da-a37eaf7646c6-S0 at slave(751)@172.17.0.2:33432 (78aba094e6f9) 3: I1114 02:53:07.020391 19949 slave.cpp:1207] Registered with master [email protected]:33432; given agent ID 56cf5864-f4d1-40e5-90da-a37eaf7646c6-S0 3: I1114 02:53:07.020484 19950 status_update_manager.cpp:184] Resuming sending status updates 3: I1114 02:53:07.020277 19959 master.cpp:6263] Registered agent 56cf5864-f4d1-40e5-90da-a37eaf7646c6-S0 at slave(751)@172.17.0.2:33432 (78aba094e6f9) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] 3: I1114 02:53:07.020619 19966 hierarchical.cpp:593] Added agent 56cf5864-f4d1-40e5-90da-a37eaf7646c6-S0 (78aba094e6f9) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {}) 3: I1114 02:53:07.020727 19949 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_b5SeIO/meta/slaves/56cf5864-f4d1-40e5-90da-a37eaf7646c6-S0/slave.info' 3: I1114 02:53:07.020927 19966 hierarchical.cpp:1450] Performed allocation for 1 agents in 141181ns 3: I1114 02:53:07.021145 19949 slave.cpp:1285] Forwarding total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000] 3: I1114 02:53:07.021217 19949 slave.cpp:1295] Forwarding total oversubscribed resources {} 3: I1114 02:53:07.021803 19949 master.cpp:7051] Received update of agent 56cf5864-f4d1-40e5-90da-a37eaf7646c6-S0 at slave(751)@172.17.0.2:33432 (78aba094e6f9) with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000] 3: I1114 02:53:07.021860 19949 master.cpp:7064] Received update of agent 56cf5864-f4d1-40e5-90da-a37eaf7646c6-S0 at slave(751)@172.17.0.2:33432 (78aba094e6f9) with total oversubscribed resources {} 3: I1114 02:53:07.022055 19949 master.cpp:7082] Ignoring update on agent 56cf5864-f4d1-40e5-90da-a37eaf7646c6-S0 at slave(751)@172.17.0.2:33432 (78aba094e6f9) as it reports no changes 3: I1114 02:53:07.022234 19947 http_connection.hpp:221] New endpoint detected at http://172.17.0.2:33432/slave(751)/api/v1/resource_provider 3: I1114 02:53:07.024454 19955 http_connection.hpp:277] Connected with the remote endpoint at http://172.17.0.2:33432/slave(751)/api/v1/resource_provider 3: I1114 02:53:07.025409 19948 http_connection.hpp:129] Sending 1 call to http://172.17.0.2:33432/slave(751)/api/v1/resource_provider 3: I1114 02:53:07.026854 19968 process.cpp:3925] Handling HTTP event for process 'slave(751)' with path: '/slave(751)/api/v1/resource_provider' 3: I1114 02:53:07.028084 19963 http.cpp:1185] HTTP POST for /slave(751)/api/v1/resource_provider from 172.17.0.2:58852 3: I1114 02:53:07.028414 19951 manager.cpp:381] Subscribing resource provider {"name":"test","type":"org.apache.mesos.rp.test"} 3: I1114 02:53:07.031831 19947 http_connection.hpp:129] Sending 3 call to http://172.17.0.2:33432/slave(751)/api/v1/resource_provider 3: I1114 02:53:07.033026 19958 process.cpp:3925] Handling HTTP event for process 'slave(751)' with path: '/slave(751)/api/v1/resource_provider' 3: I1114 02:53:07.034543 19969 http.cpp:1185] HTTP POST for /slave(751)/api/v1/resource_provider from 172.17.0.2:58850 3: I1114 02:53:07.035089 19967 slave.cpp:6719] Handling resource provider message 'UPDATE_TOTAL_RESOURCES: 737fa65c-a99a-443b-a7da-66ca74923908 disk[RAW]:200' 3: I1114 02:53:07.035246 19967 slave.cpp:6764] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200 3: I1114 02:53:07.036166 19960 master.cpp:7051] Received update of agent 56cf5864-f4d1-40e5-90da-a37eaf7646c6-S0 at slave(751)@172.17.0.2:33432 (78aba094e6f9) with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200 3: I1114 02:53:07.037621 19945 scheduler.cpp:190] Version: 1.5.0 3: I1114 02:53:07.037796 19949 hierarchical.cpp:660] Agent 56cf5864-f4d1-40e5-90da-a37eaf7646c6-S0 (78aba094e6f9) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200 3: I1114 02:53:07.037957 19951 scheduler.cpp:313] Using default 'basic' HTTP authenticatee 3: I1114 02:53:07.038141 19949 hierarchical.cpp:1450] Performed allocation for 1 agents in 151824ns 3: I1114 02:53:07.038491 19965 scheduler.cpp:496] New master detected at [email protected]:33432 3: I1114 02:53:07.038542 19965 scheduler.cpp:505] Waiting for 0ns before initiating a re-(connection) attempt with the master 3: I1114 02:53:07.041494 19967 scheduler.cpp:387] Connected with the master at http://172.17.0.2:33432/master/api/v1/scheduler 3: I1114 02:53:07.042599 19964 scheduler.cpp:249] Sending SUBSCRIBE call to http://172.17.0.2:33432/master/api/v1/scheduler 3: I1114 02:53:07.044898 19946 process.cpp:3925] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1114 02:53:07.046114 19948 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:58856 3: I1114 02:53:07.046371 19948 master.cpp:2613] Received subscription request for HTTP framework 'default' 3: I1114 02:53:07.046479 19948 master.cpp:2278] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' 3: I1114 02:53:07.047006 19952 master.cpp:2748] Subscribing framework 'default' with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ] 3: I1114 02:53:07.047770 19963 hierarchical.cpp:303] Added framework 56cf5864-f4d1-40e5-90da-a37eaf7646c6-0000 3: I1114 02:53:07.048667 19950 scheduler.cpp:741] Enqueuing event SUBSCRIBED received from http://172.17.0.2:33432/master/api/v1/scheduler 3: I1114 02:53:07.049100 19950 scheduler.cpp:741] Enqueuing event HEARTBEAT received from http://172.17.0.2:33432/master/api/v1/scheduler 3: I1114 02:53:07.049335 19963 hierarchical.cpp:1450] Performed allocation for 1 agents in 1.402777ms 3: I1114 02:53:07.050079 19954 master.cpp:8115] Sending 1 offers to framework 56cf5864-f4d1-40e5-90da-a37eaf7646c6-0000 (default) 3: I1114 02:53:07.051934 19957 scheduler.cpp:741] Enqueuing event OFFERS received from http://172.17.0.2:33432/master/api/v1/scheduler 3: I1114 02:53:07.053679 19961 scheduler.cpp:249] Sending ACCEPT call to http://172.17.0.2:33432/master/api/v1/scheduler 3: I1114 02:53:07.055368 19966 process.cpp:3925] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1114 02:53:07.056813 19952 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:58854 3: I1114 02:53:07.057857 19952 master.cpp:10017] Removing offer 56cf5864-f4d1-40e5-90da-a37eaf7646c6-O0 3: I1114 02:53:07.057960 19952 master.cpp:4228] Processing ACCEPT call for offers: [ 56cf5864-f4d1-40e5-90da-a37eaf7646c6-O0 ] on agent 56cf5864-f4d1-40e5-90da-a37eaf7646c6-S0 at slave(751)@172.17.0.2:33432 (78aba094e6f9) for framework 56cf5864-f4d1-40e5-90da-a37eaf7646c6-0000 (default) 3: I1114 02:53:07.058682 19952 master.cpp:5293] Processing CREATE_BLOCK operation with source disk(allocated: *)[RAW]:200 from framework 56cf5864-f4d1-40e5-90da-a37eaf7646c6-0000 (default) to agent 56cf5864-f4d1-40e5-90da-a37eaf7646c6-S0 at slave(751)@172.17.0.2:33432 (78aba094e6f9) 3: I1114 02:53:07.059255 19952 master.cpp:9900] Sending offer operation r2Í;µF3¬y±¨¤ to agent 56cf5864-f4d1-40e5-90da-a37eaf7646c6-S0 at slave(751)@172.17.0.2:33432 (78aba094e6f9) 3: I1114 02:53:07.060523 19957 hierarchical.cpp:1125] Recovered cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200, allocated: disk(allocated: *)[RAW]:200) on agent 56cf5864-f4d1-40e5-90da-a37eaf7646c6-S0 from framework 56cf5864-f4d1-40e5-90da-a37eaf7646c6-0000 3: I1114 02:53:07.060608 19957 hierarchical.cpp:1171] Framework 56cf5864-f4d1-40e5-90da-a37eaf7646c6-0000 filtered agent 56cf5864-f4d1-40e5-90da-a37eaf7646c6-S0 for 5secs 3: I1114 02:53:07.062851 19966 http_connection.hpp:129] Sending 2 call to http://172.17.0.2:33432/slave(751)/api/v1/resource_provider 3: I1114 02:53:07.063822 19951 process.cpp:3925] Handling HTTP event for process 'slave(751)' with path: '/slave(751)/api/v1/resource_provider' 3: I1114 02:53:07.107501 19953 http.cpp:1185] HTTP POST for /slave(751)/api/v1/resource_provider from 172.17.0.2:58850 3: I1114 02:53:07.108409 19955 slave.cpp:6719] Handling resource provider message 'UPDATE_OFFER_OPERATION_STATUS: {"framework_id":{"value":"56cf5864-f4d1-40e5-90da-a37eaf7646c6-0000"},"status":{"state":"OFFER_OPERATION_FINISHED","converted_resources":[{"provider_id":{"value":"737fa65c-a99a-443b-a7da-66ca74923908"},"name":"disk","type":"SCALAR","scalar":{"value":200.0},"allocation_info":{"role":"*"},"disk":{"source":{"type":"BLOCK"}}}]},"latest_status":{"state":"OFFER_OPERATION_FINISHED","converted_resources":[{"provider_id":{"value":"737fa65c-a99a-443b-a7da-66ca74923908"},"name":"disk","type":"SCALAR","scalar":{"value":200.0},"allocation_info":{"role":"*"},"disk":{"source":{"type":"BLOCK"}}}]},"operation_uuid":"cjLNO4+1RjOHrJR5sYeopA=="}' 3: I1114 02:53:07.108831 19955 slave.cpp:6893] Updating the state of offer operation '' (uuid: r2Í;µF3¬y±¨¤) of framework 56cf5864-f4d1-40e5-90da-a37eaf7646c6-0000 (latest state: OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED) 3: I1114 02:53:07.109284 19955 slave.cpp:6817] Forwarding status update of offer operation '' (uuid: 7232cd3b-8fb5-4633-87ac-9479b187a8a4) for framework 56cf5864-f4d1-40e5-90da-a37eaf7646c6-0000 3: I1114 02:53:07.109923 19957 master.cpp:9698] Updating the state of offer operation '' (uuid: 7232cd3b-8fb5-4633-87ac-9479b187a8a4) of framework 56cf5864-f4d1-40e5-90da-a37eaf7646c6-0000 (latest state: OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED) 3: F1114 02:53:07.110375 19957 master.cpp:11027] CHECK_SOME(resources): cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200 does not contain disk(allocated: *)[RAW]:200 3: *** Check failure stack trace: *** 3: @ 0x2ba43f3dc9fd google::LogMessage::Fail() 3: @ 0x2ba43f3de89d google::LogMessage::SendToLog() 3: @ 0x2ba43f3dc5ec google::LogMessage::Flush() 3: @ 0x2ba43f3df1be google::LogMessageFatal::~LogMessageFatal() 3: I1114 02:53:07.112073 19965 hierarchical.cpp:849] Updated allocation of framework 56cf5864-f4d1-40e5-90da-a37eaf7646c6-0000 on agent 56cf5864-f4d1-40e5-90da-a37eaf7646c6-S0 from disk(allocated: *)[RAW]:200 to disk(allocated: *)[BLOCK]:200 3: I1114 02:53:07.112476 19965 hierarchical.cpp:1125] Recovered disk(allocated: *)[BLOCK]:200 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[BLOCK]:200, allocated: {}) on agent 56cf5864-f4d1-40e5-90da-a37eaf7646c6-S0 from framework 56cf5864-f4d1-40e5-90da-a37eaf7646c6-0000 3: I1114 02:53:07.117007 19959 hierarchical.cpp:1450] Performed allocation for 1 agents in 1.96546ms 3: @ 0xc98678 _CheckFatal::~_CheckFatal() 3: @ 0x2ba43c7413d1 mesos::internal::master::Slave::apply() 3: @ 0x2ba43c737394 mesos::internal::master::Master::updateOfferOperation() 3: @ 0x2ba43c7223a7 mesos::internal::master::Master::offerOperationStatusUpdate() 3: @ 0x2ba43c786b45 ProtobufProcess<>::_handlerM<>() 3: @ 0x2ba43c840b0f _ZNSt5_BindIFPFvPN5mesos8internal6master6MasterEMS3_FvRKNS1_26OfferOperationStatusUpdateEERKN7process4UPIDERKSsES4_S9_St12_PlaceholderILi1EESI_ILi2EEEE6__callIvJSD_SF_EJLm0ELm1ELm2ELm3EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE 3: @ 0x2ba43c81ed46 _ZNSt5_BindIFPFvPN5mesos8internal6master6MasterEMS3_FvRKNS1_26OfferOperationStatusUpdateEERKN7process4UPIDERKSsES4_S9_St12_PlaceholderILi1EESI_ILi2EEEEclIJSD_SF_EvEET0_DpOT_ 3: @ 0x2ba43c7f3c82 std::_Function_handler<>::_M_invoke() 3: @ 0xe84ae3 std::function<>::operator()() 3: @ 0x2ba43c76811c ProtobufProcess<>::visit() 3: @ 0x2ba43c6f81c0 mesos::internal::master::Master::_visit() 3: @ 0x2ba43c6f78d2 mesos::internal::master::Master::visit() 3: @ 0x2ba43c74cf68 process::MessageEvent::visit() 3: @ 0xcb24d2 process::ProcessBase::serve() 3: @ 0x2ba43ee23c7d process::ProcessManager::resume() 3: @ 0x2ba43ee200dc _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv 3: @ 0x2ba43ee32404 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE 3: @ 0x2ba43ee3235b _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv 3: @ 0x2ba43ee322f4 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv 3: @ 0x2ba441e12a60 (unknown) 3: @ 0x2ba43f608184 start_thread 3: @ 0x2ba44267effd (unknown) 3/3 Test #3: MesosTests .......................***Exception: Other471.04 sec 67% tests passed, 1 tests failed out of 3 Total Test time (real) = 514.37 sec The following tests FAILED: 3 - MesosTests (OTHER_FAULT) Errors while running CTest make[3]: *** [CMakeFiles/check] Error 8 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-1510625256-18842 Untagged: mesos-1510625256-18842:latest Deleted: sha256:d1892b9f5c403516c58ff845e2b2707161a4c3611cdad47730bc75c558de84e2 Deleted: sha256:6c4f2871cbe8448899913bbaeb47a8cd4742efbcde5673431eace14e7e57b239 Deleted: sha256:99de6899a2a171a85034acfed5a4ace68b629d5f45ed0dabee29b2031f356adf Deleted: sha256:327d5d20b026ecec5eebbf621c1a31fd6d26416e2a088bd28632dba576ed4dcb Deleted: sha256:4fe943add9fdd4f321ff846706c39f137501ad41f310b2638fb2657efa8841ca Deleted: sha256:945727d116337ce65f3fcef7ceb8589076d2633e447f855b404d45a0e7c06fb1 Deleted: sha256:c46e68e2a7dc3ddc5f7c6466b96b4c24cde4abb2bf52eadc2caf83866f4f3434 Deleted: sha256:3b13cf80f255be0b45495a0874165c02db855d5b5b84afdfde48e81a760742ef Deleted: sha256:be8391fd1d3884568ce0097c4ee3a18bc103baeb77472c4c9ce0833652fa6f04 Deleted: sha256:4973213dd35afd755f2a7867b35c6b2442e4a2c5fec8ec9ba908b4140753a8ff Deleted: sha256:873340b1d0e2f0c62e49761447a4789bb87202963766c6148c7afef94dfdaa09 Deleted: sha256:ad89a637bd87f107f84beceac20d2b88da6dedec807f0ae4ff7de0829e025421 Deleted: sha256:419f03c6c4e98ed6b8b5359716b6af6e3432a259ea4cce91be06ae6e482a2b7e Deleted: sha256:2a455152552f8a2f8f9b7871a74834235f9d894497931c3e67b3a6470b4ed01a Deleted: sha256:626aadc8ec2d50dd27ca1845fd63bbd8380b303fba7d0ca1bbac559ecdc07846 Deleted: sha256:744d0f652cb14b258e93c5e8fc9b0f4db3fc91132edd66076c4c709803b6cee1 Deleted: sha256:7009d6d382c25f0e628f4d2bc2218df70ca0e6a6e1026046972c852694dc9e0f Deleted: sha256:6a38d234edebe52410678d6f9fd04a421a52f32b6bb10a65512ff8215fc115d9 Deleted: sha256:402ab624987e19031cb39173254a39275cfe38242f8f0650ca9ae57a542b6248 Deleted: sha256:9eae2be8af28d803e3b013d19a719a699a214361d7debbef94fff905741f9bbd Deleted: sha256:68d764c2d6ef62285b8aef3c6a16e0f213c58644194af49985a9b0591affe0f8 Deleted: sha256:f49e951e96eb61ca38cb75b20533f4d6d27534ed9fcb4bf4d86bd1dad9a2a9e0 Deleted: sha256:22a4192227177fbc6a2e51401c51c5389d2f639be274c51104e8e5f41793a7c4 Deleted: sha256:e7ec732d07c9b806b9a5f6d34cc2889026f1913da069796f596d030e12dd5f17 Deleted: sha256:4d36b376f85e6c23cbe82fa2813eee1ecb7ae84d574d0d5f1584efc7bf7613f3 Deleted: sha256:00863748d409fefe5e0db6c12ee9b8e600da6f0780c784748f628a9ef878df87 Deleted: sha256:937bb55093f04770d26c8c9df63cfcf984e42b0e14ab598e03a80a224717ef99 Deleted: sha256:c6604f1e3909a5de4b6718f077dfe0acbfe2013bdcfa5d8e3e630a0c7f84ba87 Deleted: sha256:429369ba654b02f981aed437542d65625544c5000f511aa25f46110cfb8a1138 Deleted: sha256:29ea2892fc686f856bf342706ae8a643c3714f809b320ee0a06f7403c4a19f64 Build step 'Execute shell' marked build as failure Not sending mail to unregistered user [email protected] Not sending mail to unregistered user [email protected]
