See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4451/display/redirect?page=changes>
Changes: [yujie.jay] Handled the resource conversion for new operations in master. [songzihao1990] Added a test `CgroupsIsolatorTest.ROOT_CGROUPS_BlkioUsage`. ------------------------------------------ [...truncated 27.02 MB...] 3: I1114 01:36:48.748714 15986 slave.cpp:610] Agent hostname: 48c37892027e 3: I1114 01:36:48.748848 15985 status_update_manager.cpp:177] Pausing sending status updates 3: I1114 01:36:48.750574 15971 state.cpp:64] Recovering state from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_AgentEndpoint_1_GLixaU/meta' 3: I1114 01:36:48.750905 15983 status_update_manager.cpp:203] Recovering status update manager 3: I1114 01:36:48.751093 15989 containerizer.cpp:662] Recovering containerizer 3: I1114 01:36:48.752490 15978 provisioner.cpp:416] Provisioner recovery complete 3: I1114 01:36:48.752845 15973 slave.cpp:6432] Finished recovery 3: I1114 01:36:48.753886 15972 status_update_manager.cpp:177] Pausing sending status updates 3: I1114 01:36:48.753916 15983 slave.cpp:1007] New master detected at [email protected]:41188 3: I1114 01:36:48.753973 15983 slave.cpp:1042] Detecting new master 3: I1114 01:36:48.757131 15984 process.cpp:3925] Handling HTTP event for process 'slave(746)' with path: '/slave(746)/api/v1/resource_provider' 3: I1114 01:36:48.758479 15985 http.cpp:1185] HTTP POST for /slave(746)/api/v1/resource_provider from 172.17.0.4:35116 3: I1114 01:36:48.759063 15978 manager.cpp:381] Subscribing resource provider {"name":"test","type":"org.apache.mesos.rp.test"} 3: I1114 01:36:48.766346 15974 slave.cpp:883] Agent terminating 3: I1114 01:36:48.772132 15986 master.cpp:1151] Master terminating 3: [ OK ] ContentType/ResourceProviderManagerHttpApiTest.AgentEndpoint/1 (51 ms) 3: [ RUN ] ContentType/ResourceProviderManagerHttpApiTest.ConvertResources/0 3: I1114 01:36:48.778712 15965 cluster.cpp:162] Creating default 'local' authorizer 3: I1114 01:36:48.781209 15979 master.cpp:448] Master 0f5a665e-948d-4b30-8b79-58017d9e4cc6 (48c37892027e) started on 172.17.0.4:41188 3: I1114 01:36:48.781246 15979 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/qQByPw/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/qQByPw/master" --zk_session_timeout="10secs" 3: I1114 01:36:48.781576 15979 master.cpp:499] Master only allowing authenticated frameworks to register 3: I1114 01:36:48.781584 15979 master.cpp:505] Master only allowing authenticated agents to register 3: I1114 01:36:48.781587 15979 master.cpp:511] Master only allowing authenticated HTTP frameworks to register 3: I1114 01:36:48.781592 15979 credentials.hpp:37] Loading credentials for authentication from '/tmp/qQByPw/credentials' 3: I1114 01:36:48.781891 15979 master.cpp:555] Using default 'crammd5' authenticator 3: I1114 01:36:48.782042 15979 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1114 01:36:48.782196 15979 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1114 01:36:48.782305 15979 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1114 01:36:48.782403 15979 master.cpp:634] Authorization enabled 3: I1114 01:36:48.782562 15985 hierarchical.cpp:171] Initialized hierarchical allocator process 3: I1114 01:36:48.782583 15978 whitelist_watcher.cpp:77] No whitelist given 3: I1114 01:36:48.785297 15971 master.cpp:2213] Elected as the leading master! 3: I1114 01:36:48.785315 15971 master.cpp:1693] Recovering from registrar 3: I1114 01:36:48.785421 15975 registrar.cpp:347] Recovering registrar 3: I1114 01:36:48.785984 15975 registrar.cpp:391] Successfully fetched the registry (0B) in 528896ns 3: I1114 01:36:48.786108 15975 registrar.cpp:495] Applied 1 operations in 43849ns; attempting to update the registry 3: I1114 01:36:48.786643 15975 registrar.cpp:552] Successfully updated the registry in 476672ns 3: I1114 01:36:48.786751 15975 registrar.cpp:424] Successfully recovered registrar 3: I1114 01:36:48.787081 15984 master.cpp:1806] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register 3: I1114 01:36:48.787107 15988 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover 3: W1114 01:36:48.791599 15965 process.cpp:3178] Attempted to spawn already running process [email protected]:41188 3: I1114 01:36:48.792460 15965 containerizer.cpp:301] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } 3: W1114 01:36:48.793001 15965 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges 3: W1114 01:36:48.793118 15965 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges 3: I1114 01:36:48.793150 15965 provisioner.cpp:255] Using default backend 'copy' 3: I1114 01:36:48.794930 15965 cluster.cpp:448] Creating default 'local' authorizer 3: I1114 01:36:48.796785 15988 slave.cpp:262] Mesos agent started on (747)@172.17.0.4:41188 3: I1114 01:36:48.796808 15988 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_X79wLW/store/appc" --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_X79wLW/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_X79wLW/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_ResourceProviderManagerHttpApiTest_ConvertResources_0_X79wLW/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_X79wLW/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_X79wLW" --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_HClxNJ" --zk_session_timeout="10secs" 3: I1114 01:36:48.797241 15988 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_X79wLW/credential' 3: I1114 01:36:48.797360 15988 slave.cpp:295] Agent using credential for: test-principal 3: I1114 01:36:48.797376 15988 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_X79wLW/http_credentials' 3: I1114 01:36:48.797556 15988 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1114 01:36:48.798835 15988 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 01:36:48.799024 15988 slave.cpp:601] Agent attributes: [ ] 3: I1114 01:36:48.799036 15988 slave.cpp:610] Agent hostname: 48c37892027e 3: I1114 01:36:48.799157 15971 status_update_manager.cpp:177] Pausing sending status updates 3: I1114 01:36:48.800668 15981 state.cpp:64] Recovering state from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_HClxNJ/meta' 3: I1114 01:36:48.800907 15989 status_update_manager.cpp:203] Recovering status update manager 3: I1114 01:36:48.801095 15969 containerizer.cpp:662] Recovering containerizer 3: I1114 01:36:48.802165 15972 provisioner.cpp:416] Provisioner recovery complete 3: I1114 01:36:48.802386 15971 slave.cpp:6432] Finished recovery 3: I1114 01:36:48.802979 15974 status_update_manager.cpp:177] Pausing sending status updates 3: I1114 01:36:48.803025 15971 slave.cpp:1007] New master detected at [email protected]:41188 3: I1114 01:36:48.803119 15971 slave.cpp:1042] Detecting new master 3: I1114 01:36:48.804317 15981 slave.cpp:1069] Authenticating with master [email protected]:41188 3: I1114 01:36:48.804370 15981 slave.cpp:1078] Using default CRAM-MD5 authenticatee 3: I1114 01:36:48.804610 15977 authenticatee.cpp:121] Creating new client SASL connection 3: I1114 01:36:48.804878 15986 master.cpp:8285] Authenticating slave(747)@172.17.0.4:41188 3: I1114 01:36:48.804965 15989 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1374)@172.17.0.4:41188 3: I1114 01:36:48.805204 15970 authenticator.cpp:98] Creating new server SASL connection 3: I1114 01:36:48.805397 15984 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1114 01:36:48.805419 15984 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1114 01:36:48.805512 15975 authenticator.cpp:204] Received SASL authentication start 3: I1114 01:36:48.805559 15975 authenticator.cpp:326] Authentication requires more steps 3: I1114 01:36:48.805660 15967 authenticatee.cpp:259] Received SASL authentication step 3: I1114 01:36:48.805760 15985 authenticator.cpp:232] Received SASL authentication step 3: I1114 01:36:48.805788 15985 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '48c37892027e' server FQDN: '48c37892027e' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1114 01:36:48.805799 15985 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1114 01:36:48.805832 15985 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1114 01:36:48.805847 15985 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '48c37892027e' server FQDN: '48c37892027e' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1114 01:36:48.805855 15985 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1114 01:36:48.805860 15985 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1114 01:36:48.805871 15985 authenticator.cpp:318] Authentication success 3: I1114 01:36:48.805927 15978 authenticatee.cpp:299] Authentication success 3: I1114 01:36:48.806006 15982 master.cpp:8315] Successfully authenticated principal 'test-principal' at slave(747)@172.17.0.4:41188 3: I1114 01:36:48.806033 15969 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1374)@172.17.0.4:41188 3: I1114 01:36:48.806151 15978 slave.cpp:1161] Successfully authenticated with master [email protected]:41188 3: I1114 01:36:48.806370 15978 slave.cpp:1682] Will retry registration in 1043us if necessary 3: I1114 01:36:48.806548 15972 master.cpp:6032] Received register agent message from slave(747)@172.17.0.4:41188 (48c37892027e) 3: I1114 01:36:48.806578 15972 master.cpp:3870] Authorizing agent with principal 'test-principal' 3: I1114 01:36:48.806942 15983 master.cpp:6092] Authorized registration of agent at slave(747)@172.17.0.4:41188 (48c37892027e) 3: I1114 01:36:48.807057 15983 master.cpp:6185] Registering agent at slave(747)@172.17.0.4:41188 (48c37892027e) with id 0f5a665e-948d-4b30-8b79-58017d9e4cc6-S0 3: I1114 01:36:48.807358 15976 registrar.cpp:495] Applied 1 operations in 63778ns; attempting to update the registry 3: I1114 01:36:48.807735 15971 slave.cpp:1682] Will retry registration in 31.184388ms if necessary 3: I1114 01:36:48.807847 15976 registrar.cpp:552] Successfully updated the registry in 432896ns 3: I1114 01:36:48.807862 15986 master.cpp:6026] Ignoring register agent message from slave(747)@172.17.0.4:41188 (48c37892027e) as registration is already in progress 3: I1114 01:36:48.808004 15989 master.cpp:6232] Admitted agent 0f5a665e-948d-4b30-8b79-58017d9e4cc6-S0 at slave(747)@172.17.0.4:41188 (48c37892027e) 3: I1114 01:36:48.808584 15984 slave.cpp:1207] Registered with master [email protected]:41188; given agent ID 0f5a665e-948d-4b30-8b79-58017d9e4cc6-S0 3: I1114 01:36:48.808683 15987 status_update_manager.cpp:184] Resuming sending status updates 3: I1114 01:36:48.808516 15989 master.cpp:6263] Registered agent 0f5a665e-948d-4b30-8b79-58017d9e4cc6-S0 at slave(747)@172.17.0.4:41188 (48c37892027e) 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 01:36:48.808814 15975 hierarchical.cpp:593] Added agent 0f5a665e-948d-4b30-8b79-58017d9e4cc6-S0 (48c37892027e) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {}) 3: I1114 01:36:48.808851 15984 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_HClxNJ/meta/slaves/0f5a665e-948d-4b30-8b79-58017d9e4cc6-S0/slave.info' 3: I1114 01:36:48.809065 15975 hierarchical.cpp:1450] Performed allocation for 1 agents in 137131ns 3: I1114 01:36:48.809162 15984 slave.cpp:1285] Forwarding total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000] 3: I1114 01:36:48.809214 15984 slave.cpp:1295] Forwarding total oversubscribed resources {} 3: I1114 01:36:48.809702 15985 master.cpp:7051] Received update of agent 0f5a665e-948d-4b30-8b79-58017d9e4cc6-S0 at slave(747)@172.17.0.4:41188 (48c37892027e) with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000] 3: I1114 01:36:48.809748 15985 master.cpp:7064] Received update of agent 0f5a665e-948d-4b30-8b79-58017d9e4cc6-S0 at slave(747)@172.17.0.4:41188 (48c37892027e) with total oversubscribed resources {} 3: I1114 01:36:48.809903 15985 master.cpp:7082] Ignoring update on agent 0f5a665e-948d-4b30-8b79-58017d9e4cc6-S0 at slave(747)@172.17.0.4:41188 (48c37892027e) as it reports no changes 3: I1114 01:36:48.810047 15966 http_connection.hpp:221] New endpoint detected at http://172.17.0.4:41188/slave(747)/api/v1/resource_provider 3: I1114 01:36:48.812052 15981 http_connection.hpp:277] Connected with the remote endpoint at http://172.17.0.4:41188/slave(747)/api/v1/resource_provider 3: I1114 01:36:48.812840 15984 http_connection.hpp:129] Sending 1 call to http://172.17.0.4:41188/slave(747)/api/v1/resource_provider 3: I1114 01:36:48.813836 15985 process.cpp:3925] Handling HTTP event for process 'slave(747)' with path: '/slave(747)/api/v1/resource_provider' 3: I1114 01:36:48.815398 15988 http.cpp:1185] HTTP POST for /slave(747)/api/v1/resource_provider from 172.17.0.4:35118 3: I1114 01:36:48.815655 15974 manager.cpp:381] Subscribing resource provider {"name":"test","type":"org.apache.mesos.rp.test"} 3: I1114 01:36:48.817735 15968 http_connection.hpp:129] Sending 3 call to http://172.17.0.4:41188/slave(747)/api/v1/resource_provider 3: I1114 01:36:48.818655 15987 process.cpp:3925] Handling HTTP event for process 'slave(747)' with path: '/slave(747)/api/v1/resource_provider' 3: I1114 01:36:48.819473 15989 http.cpp:1185] HTTP POST for /slave(747)/api/v1/resource_provider from 172.17.0.4:35120 3: I1114 01:36:48.819859 15967 slave.cpp:6719] Handling resource provider message 'UPDATE_TOTAL_RESOURCES: b2fc6c24-0d94-4289-95a5-678e00ee61bd disk[RAW]:200' 3: I1114 01:36:48.819960 15967 slave.cpp:6764] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200 3: I1114 01:36:48.820852 15984 master.cpp:7051] Received update of agent 0f5a665e-948d-4b30-8b79-58017d9e4cc6-S0 at slave(747)@172.17.0.4:41188 (48c37892027e) with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200 3: I1114 01:36:48.822043 15965 scheduler.cpp:190] Version: 1.5.0 3: I1114 01:36:48.822371 15974 scheduler.cpp:313] Using default 'basic' HTTP authenticatee 3: I1114 01:36:48.822559 15983 hierarchical.cpp:660] Agent 0f5a665e-948d-4b30-8b79-58017d9e4cc6-S0 (48c37892027e) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200 3: I1114 01:36:48.822773 15986 scheduler.cpp:496] New master detected at [email protected]:41188 3: I1114 01:36:48.822793 15986 scheduler.cpp:505] Waiting for 0ns before initiating a re-(connection) attempt with the master 3: I1114 01:36:48.822911 15983 hierarchical.cpp:1450] Performed allocation for 1 agents in 190385ns 3: I1114 01:36:48.825866 15985 scheduler.cpp:387] Connected with the master at http://172.17.0.4:41188/master/api/v1/scheduler 3: I1114 01:36:48.826771 15977 scheduler.cpp:249] Sending SUBSCRIBE call to http://172.17.0.4:41188/master/api/v1/scheduler 3: I1114 01:36:48.828014 15989 process.cpp:3925] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1114 01:36:48.829107 15978 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:35126 3: I1114 01:36:48.829339 15978 master.cpp:2613] Received subscription request for HTTP framework 'default' 3: I1114 01:36:48.829380 15978 master.cpp:2278] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' 3: I1114 01:36:48.829862 15984 master.cpp:2748] Subscribing framework 'default' with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ] 3: I1114 01:36:48.830620 15968 hierarchical.cpp:303] Added framework 0f5a665e-948d-4b30-8b79-58017d9e4cc6-0000 3: I1114 01:36:48.831156 15981 scheduler.cpp:741] Enqueuing event SUBSCRIBED received from http://172.17.0.4:41188/master/api/v1/scheduler 3: I1114 01:36:48.831493 15981 scheduler.cpp:741] Enqueuing event HEARTBEAT received from http://172.17.0.4:41188/master/api/v1/scheduler 3: I1114 01:36:48.832033 15968 hierarchical.cpp:1450] Performed allocation for 1 agents in 1.303365ms 3: I1114 01:36:48.832624 15974 master.cpp:8115] Sending 1 offers to framework 0f5a665e-948d-4b30-8b79-58017d9e4cc6-0000 (default) 3: I1114 01:36:48.834287 15983 scheduler.cpp:741] Enqueuing event OFFERS received from http://172.17.0.4:41188/master/api/v1/scheduler 3: I1114 01:36:48.835750 15971 scheduler.cpp:249] Sending ACCEPT call to http://172.17.0.4:41188/master/api/v1/scheduler 3: I1114 01:36:48.836865 15977 process.cpp:3925] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1114 01:36:48.837846 15984 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:35128 3: I1114 01:36:48.838770 15984 master.cpp:10017] Removing offer 0f5a665e-948d-4b30-8b79-58017d9e4cc6-O0 3: I1114 01:36:48.838874 15984 master.cpp:4228] Processing ACCEPT call for offers: [ 0f5a665e-948d-4b30-8b79-58017d9e4cc6-O0 ] on agent 0f5a665e-948d-4b30-8b79-58017d9e4cc6-S0 at slave(747)@172.17.0.4:41188 (48c37892027e) for framework 0f5a665e-948d-4b30-8b79-58017d9e4cc6-0000 (default) 3: I1114 01:36:48.839503 15984 master.cpp:5293] Processing CREATE_BLOCK operation with source disk(allocated: *)[RAW]:200 from framework 0f5a665e-948d-4b30-8b79-58017d9e4cc6-0000 (default) to agent 0f5a665e-948d-4b30-8b79-58017d9e4cc6-S0 at slave(747)@172.17.0.4:41188 (48c37892027e) 3: I1114 01:36:48.840076 15984 master.cpp:9900] Sending offer operation dhM ÿ¸'¾í[× to agent 0f5a665e-948d-4b30-8b79-58017d9e4cc6-S0 at slave(747)@172.17.0.4:41188 (48c37892027e) 3: I1114 01:36:48.841051 15975 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 0f5a665e-948d-4b30-8b79-58017d9e4cc6-S0 from framework 0f5a665e-948d-4b30-8b79-58017d9e4cc6-0000 3: I1114 01:36:48.841112 15975 hierarchical.cpp:1171] Framework 0f5a665e-948d-4b30-8b79-58017d9e4cc6-0000 filtered agent 0f5a665e-948d-4b30-8b79-58017d9e4cc6-S0 for 5secs 3: I1114 01:36:48.842536 15988 http_connection.hpp:129] Sending 2 call to http://172.17.0.4:41188/slave(747)/api/v1/resource_provider 3: I1114 01:36:48.843299 15974 process.cpp:3925] Handling HTTP event for process 'slave(747)' with path: '/slave(747)/api/v1/resource_provider' 3: I1114 01:36:48.881867 15984 http.cpp:1185] HTTP POST for /slave(747)/api/v1/resource_provider from 172.17.0.4:35120 3: I1114 01:36:48.882691 15986 slave.cpp:6719] Handling resource provider message 'UPDATE_OFFER_OPERATION_STATUS: {"framework_id":{"value":"0f5a665e-948d-4b30-8b79-58017d9e4cc6-0000"},"status":{"state":"OFFER_OPERATION_FINISHED","converted_resources":[{"provider_id":{"value":"b2fc6c24-0d94-4289-95a5-678e00ee61bd"},"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":"b2fc6c24-0d94-4289-95a5-678e00ee61bd"},"name":"disk","type":"SCALAR","scalar":{"value":200.0},"allocation_info":{"role":"*"},"disk":{"source":{"type":"BLOCK"}}}]},"operation_uuid":"ZIOTlwFoTQyH\/7gnvu1b1w=="}' 3: I1114 01:36:48.883070 15986 slave.cpp:6893] Updating the state of offer operation '' (uuid: dhM ÿ¸'¾í[×) of framework 0f5a665e-948d-4b30-8b79-58017d9e4cc6-0000 (latest state: OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED) 3: I1114 01:36:48.883412 15986 slave.cpp:6817] Forwarding status update of offer operation '' (uuid: 64839397-0168-4d0c-87ff-b827beed5bd7) for framework 0f5a665e-948d-4b30-8b79-58017d9e4cc6-0000 3: I1114 01:36:48.883980 15973 master.cpp:9698] Updating the state of offer operation '' (uuid: 64839397-0168-4d0c-87ff-b827beed5bd7) of framework 0f5a665e-948d-4b30-8b79-58017d9e4cc6-0000 (latest state: OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED) 3: F1114 01:36:48.884403 15973 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: I1114 01:36:48.885715 15971 hierarchical.cpp:849] Updated allocation of framework 0f5a665e-948d-4b30-8b79-58017d9e4cc6-0000 on agent 0f5a665e-948d-4b30-8b79-58017d9e4cc6-S0 from disk(allocated: *)[RAW]:200 to disk(allocated: *)[BLOCK]:200 3: I1114 01:36:48.886057 15971 hierarchical.cpp:1125] Recovered disk(allocated: *)[BLOCK]:200 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[BLOCK]:200, allocated: {}) on agent 0f5a665e-948d-4b30-8b79-58017d9e4cc6-S0 from framework 0f5a665e-948d-4b30-8b79-58017d9e4cc6-0000 3: I1114 01:36:48.896199 15979 hierarchical.cpp:1450] Performed allocation for 1 agents in 1.190967ms 3: @ 0x2acdeaecaa53 google::LogMessage::SendToLog() 3: @ 0x2acdeaecaecf google::LogMessage::Flush() 3: @ 0x2acdeaece95d google::LogMessageFatal::~LogMessageFatal() 3: @ 0xa9ec97 _CheckFatal::~_CheckFatal() 3: @ 0x2acde7cbccbb mesos::internal::master::Slave::apply() 3: @ 0x2acde7cb2554 mesos::internal::master::Master::updateOfferOperation() 3: @ 0x2acde7c6358d mesos::internal::master::Master::offerOperationStatusUpdate() 3: @ 0x2acde7dd42b4 ProtobufProcess<>::_handlerM<>() 3: @ 0x2acde7dd4d9c _ZNSt5_BindIFPFvPN5mesos8internal6master6MasterEMS3_FvRKNS1_26OfferOperationStatusUpdateEERKN7process4UPIDERKSsES4_S9_St12_PlaceholderILi1EESI_ILi2EEEE6__callIvJSD_SF_EJLm0ELm1ELm2ELm3EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE 3: @ 0x2acde7dd4c93 _ZNSt5_BindIFPFvPN5mesos8internal6master6MasterEMS3_FvRKNS1_26OfferOperationStatusUpdateEERKN7process4UPIDERKSsES4_S9_St12_PlaceholderILi1EESI_ILi2EEEEclIJSD_SF_EvEET0_DpOT_ 3: @ 0x2acde7dd4a17 std::_Function_handler<>::_M_invoke() 3: @ 0xce8310 std::function<>::operator()() 3: @ 0x2acde7cd9cc6 ProtobufProcess<>::visit() 3: @ 0x2acde7c71425 mesos::internal::master::Master::_visit() 3: @ 0x2acde7c70a44 mesos::internal::master::Master::visit() 3: @ 0x2acde7c71637 mesos::internal::master::Master::visit() 3: @ 0x2acde7db33be process::MessageEvent::visit() 3: @ 0xae66a1 process::ProcessBase::serve() 3: @ 0x2acdea477282 process::ProcessManager::resume() 3: @ 0x2acdea487311 process::ProcessManager::init_threads()::$_8::operator()() 3: @ 0x2acdea487255 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvE3$_8vEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE 3: @ 0x2acdea487225 std::_Bind_simple<>::operator()() 3: @ 0x2acdea4871fc std::thread::_Impl<>::_M_run() 3: @ 0x2acdee7e7a60 (unknown) 3: @ 0x2acdeb0f7184 start_thread 3: @ 0x2acdef053ffd (unknown) 3/3 Test #3: MesosTests .......................***Exception: Other292.87 sec 67% tests passed, 1 tests failed out of 3 Total Test time (real) = 312.47 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-1510621680-9693 Untagged: mesos-1510621680-9693:latest Deleted: sha256:446f3d64ca5b259bb238997008f9020fa6549855963d26916f90ad2c8debb09b Deleted: sha256:d227d3f64a8ade8c178f55b0d90ccfeb920bf6554b29e84515732141d599d983 Deleted: sha256:c372cce0d29b0fa373b8717f4c1622d73913dcfd5de083cbdf773b3bd9de8e4f Deleted: sha256:a91fe83c976db266a8ede98fee7edab90846b2ebdaa668c46ddc4fc69ab0bb28 Deleted: sha256:b1cba9b3f664e84ba7729318af4f7c1a1e390023144934d8d6c3c7c2e8072d9e Deleted: sha256:b94bcc9c77664cd019dd021f8b7fb7867eec0ce1ed02d0b059394c8ac55cc5f8 Deleted: sha256:a2ddaaac8c4e3d26b5c74043fa1433856016d69f8862501464172135a586a4af Deleted: sha256:a719d86347b0e7e270ef4868b34db61ffe6c902c8ca20a15763307b70b72911f Deleted: sha256:fd14039c1c4c08fdde0060e02474b07165bd9f8196d01d71c15d4146b46d7129 Deleted: sha256:db149cf1c7f4598c3e855264d4f5b01bede2a2aaa29179b1a43c38edd2128dad Deleted: sha256:df1b5d3e077430a1f0251b34ccd2a39b19a9bcfe96580ea97d1ca2c8cc15590b Deleted: sha256:3ef6da32f000fb38a15d19893dabc19c4428fe126af3b2922e38bdaca6b1e6de Deleted: sha256:13e93d18424cd7c9ea978fd70d62c1c094a62a48aec2521838c5899478b13c9e Deleted: sha256:3955973f5a29ae43b3f7b25f85fb94e09b1c1994c4a8167137ea223c05aab855 Deleted: sha256:5774d6ad9bbca6b44e96e7f6d74f68967ef8514aa0ee7dff90987d376c544805 Deleted: sha256:4826a337b75e237392ae856f6974b151800376dc89d394d5f341fd3ca4fde855 Deleted: sha256:7538f0ca45a4b6c8bd2a3e46825ffeb225916b13e0eb16add94f410215103d45 Deleted: sha256:1c4f20349b300365bf0a942389d08cb7f84d6db7dc3aa04172f9b7c2b3d17f75 Deleted: sha256:affdd38f0a0360a8eaa4b62a14e249317b3d319beea874c3c524572cac07e2c7 Deleted: sha256:28de99ad1d7df074cfdf74f7fdb4bf088729d2b1ca496eef178a40394785b428 Deleted: sha256:2b898fa8f1c05edd27e9be9724bb21f7b498ea002d4420420747014977fb0b72 Deleted: sha256:44a316458b709737bb5f5ab56ab330663a8d5745df5d1c2b26a497fbdd67f5af Deleted: sha256:42a042538f107e3f11fa275a3a4a7bc263c183fe38c1fd3ff40659195f61f2a0 Deleted: sha256:22c86a97838165b470b03c84f461ad1697887496359758f64bc9097e863b5b28 Deleted: sha256:22df7620a6e45203b340b0283b10ae540b27d6d9225e8814e0206ec5cd0aef35 Deleted: sha256:5e52081a25c9890944b56e618d9986014b01f97129d1e6d4bc1c3cd782e35f6c Deleted: sha256:735c936877ca7879ad826a426832abe9fc980b343d036c8b1d476d4dc5901873 Deleted: sha256:abfd2854d5b008bf427a9491de90bc07c8913a3c1442a2d30c2e1918e35f2e13 Deleted: sha256:6657d2ac90076658f14efbbdb58e7b167333b382d3a63a948c498f5291ad5665 Deleted: sha256:7a907f56f8badff4fc671a79df0fdf23b4211850702952949c216683faf5d1c8 Build step 'Execute shell' marked build as failure Not sending mail to unregistered user [email protected] Not sending mail to unregistered user [email protected]
