You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/11/14 02:01:13 UTC

Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4451

See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,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.01 MB...]
3: I1114 02:01:01.759594 17266 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:01:01.759810 17266 slave.cpp:601] Agent attributes: [  ]
3: I1114 02:01:01.759824 17266 slave.cpp:610] Agent hostname: a6ded58a1a6e
3: I1114 02:01:01.759976 17262 status_update_manager.cpp:177] Pausing sending status updates
3: I1114 02:01:01.761761 17257 state.cpp:64] Recovering state from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_AgentEndpoint_1_LA5oVP/meta'
3: I1114 02:01:01.762054 17257 status_update_manager.cpp:203] Recovering status update manager
3: I1114 02:01:01.762269 17270 containerizer.cpp:662] Recovering containerizer
3: I1114 02:01:01.763705 17272 provisioner.cpp:416] Provisioner recovery complete
3: I1114 02:01:01.764093 17264 slave.cpp:6432] Finished recovery
3: I1114 02:01:01.764875 17270 status_update_manager.cpp:177] Pausing sending status updates
3: I1114 02:01:01.764878 17261 slave.cpp:1007] New master detected at master@172.17.0.2:53715
3: I1114 02:01:01.764956 17261 slave.cpp:1042] Detecting new master
3: I1114 02:01:01.767940 17263 process.cpp:3925] Handling HTTP event for process 'slave(746)' with path: '/slave(746)/api/v1/resource_provider'
3: I1114 02:01:01.769471 17268 http.cpp:1185] HTTP POST for /slave(746)/api/v1/resource_provider from 172.17.0.2:56904
3: I1114 02:01:01.769909 17268 manager.cpp:381] Subscribing resource provider {"name":"test","type":"org.apache.mesos.rp.test"}
3: I1114 02:01:01.777169 17256 slave.cpp:883] Agent terminating
3: I1114 02:01:01.785079 17256 master.cpp:1151] Master terminating
3: [       OK ] ContentType/ResourceProviderManagerHttpApiTest.AgentEndpoint/1 (53 ms)
3: [ RUN      ] ContentType/ResourceProviderManagerHttpApiTest.ConvertResources/0
3: I1114 02:01:01.791427 17256 cluster.cpp:162] Creating default 'local' authorizer
3: I1114 02:01:01.793934 17266 master.cpp:448] Master 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f (a6ded58a1a6e) started on 172.17.0.2:53715
3: I1114 02:01:01.793956 17266 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/fggbwA/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/fggbwA/master" --zk_session_timeout="10secs"
3: I1114 02:01:01.794342 17266 master.cpp:499] Master only allowing authenticated frameworks to register
3: I1114 02:01:01.794349 17266 master.cpp:505] Master only allowing authenticated agents to register
3: I1114 02:01:01.794353 17266 master.cpp:511] Master only allowing authenticated HTTP frameworks to register
3: I1114 02:01:01.794358 17266 credentials.hpp:37] Loading credentials for authentication from '/tmp/fggbwA/credentials'
3: I1114 02:01:01.794742 17266 master.cpp:555] Using default 'crammd5' authenticator
3: I1114 02:01:01.794930 17266 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1114 02:01:01.795136 17266 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1114 02:01:01.795346 17266 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1114 02:01:01.795469 17266 master.cpp:634] Authorization enabled
3: I1114 02:01:01.795694 17270 hierarchical.cpp:171] Initialized hierarchical allocator process
3: I1114 02:01:01.795801 17270 whitelist_watcher.cpp:77] No whitelist given
3: I1114 02:01:01.798836 17259 master.cpp:2213] Elected as the leading master!
3: I1114 02:01:01.798869 17259 master.cpp:1693] Recovering from registrar
3: I1114 02:01:01.799078 17272 registrar.cpp:347] Recovering registrar
3: I1114 02:01:01.799669 17272 registrar.cpp:391] Successfully fetched the registry (0B) in 546048ns
3: I1114 02:01:01.799779 17272 registrar.cpp:495] Applied 1 operations in 28897ns; attempting to update the registry
3: I1114 02:01:01.800366 17272 registrar.cpp:552] Successfully updated the registry in 528896ns
3: I1114 02:01:01.800495 17272 registrar.cpp:424] Successfully recovered registrar
3: I1114 02:01:01.800979 17270 master.cpp:1806] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I1114 02:01:01.801049 17265 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
3: W1114 02:01:01.805946 17256 process.cpp:3178] Attempted to spawn already running process files@172.17.0.2:53715
3: I1114 02:01:01.806962 17256 containerizer.cpp:301] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1114 02:01:01.807592 17256 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
3: W1114 02:01:01.807716 17256 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1114 02:01:01.807752 17256 provisioner.cpp:255] Using default backend 'copy'
3: I1114 02:01:01.809674 17256 cluster.cpp:448] Creating default 'local' authorizer
3: I1114 02:01:01.812094 17263 slave.cpp:262] Mesos agent started on (747)@172.17.0.2:53715
3: I1114 02:01:01.812116 17263 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_f7bGL5/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_f7bGL5/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_f7bGL5/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_f7bGL5/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_f7bGL5/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_f7bGL5" --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_z64U8P" --zk_session_timeout="10secs"
3: I1114 02:01:01.812700 17263 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_f7bGL5/credential'
3: I1114 02:01:01.812851 17263 slave.cpp:295] Agent using credential for: test-principal
3: I1114 02:01:01.812870 17263 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_f7bGL5/http_credentials'
3: I1114 02:01:01.813187 17263 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1114 02:01:01.815179 17263 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:01:01.815501 17263 slave.cpp:601] Agent attributes: [  ]
3: I1114 02:01:01.815513 17263 slave.cpp:610] Agent hostname: a6ded58a1a6e
3: I1114 02:01:01.815709 17259 status_update_manager.cpp:177] Pausing sending status updates
3: I1114 02:01:01.817486 17268 state.cpp:64] Recovering state from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_z64U8P/meta'
3: I1114 02:01:01.817883 17268 status_update_manager.cpp:203] Recovering status update manager
3: I1114 02:01:01.818120 17268 containerizer.cpp:662] Recovering containerizer
3: I1114 02:01:01.819619 17260 provisioner.cpp:416] Provisioner recovery complete
3: I1114 02:01:01.819983 17260 slave.cpp:6432] Finished recovery
3: I1114 02:01:01.820916 17270 status_update_manager.cpp:177] Pausing sending status updates
3: I1114 02:01:01.820955 17261 slave.cpp:1007] New master detected at master@172.17.0.2:53715
3: I1114 02:01:01.821013 17261 slave.cpp:1042] Detecting new master
3: I1114 02:01:01.831529 17257 slave.cpp:1069] Authenticating with master master@172.17.0.2:53715
3: I1114 02:01:01.831614 17257 slave.cpp:1078] Using default CRAM-MD5 authenticatee
3: I1114 02:01:01.831955 17259 authenticatee.cpp:121] Creating new client SASL connection
3: I1114 02:01:01.832293 17268 master.cpp:8285] Authenticating slave(747)@172.17.0.2:53715
3: I1114 02:01:01.832427 17263 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1375)@172.17.0.2:53715
3: I1114 02:01:01.832746 17258 authenticator.cpp:98] Creating new server SASL connection
3: I1114 02:01:01.832962 17262 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1114 02:01:01.832984 17262 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1114 02:01:01.833081 17267 authenticator.cpp:204] Received SASL authentication start
3: I1114 02:01:01.833150 17267 authenticator.cpp:326] Authentication requires more steps
3: I1114 02:01:01.833284 17266 authenticatee.cpp:259] Received SASL authentication step
3: I1114 02:01:01.833427 17264 authenticator.cpp:232] Received SASL authentication step
3: I1114 02:01:01.833451 17264 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'a6ded58a1a6e' server FQDN: 'a6ded58a1a6e' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1114 02:01:01.833461 17264 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1114 02:01:01.833487 17264 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1114 02:01:01.833508 17264 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'a6ded58a1a6e' server FQDN: 'a6ded58a1a6e' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1114 02:01:01.833524 17264 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1114 02:01:01.833534 17264 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1114 02:01:01.833550 17264 authenticator.cpp:318] Authentication success
3: I1114 02:01:01.833636 17272 authenticatee.cpp:299] Authentication success
3: I1114 02:01:01.833710 17265 master.cpp:8315] Successfully authenticated principal 'test-principal' at slave(747)@172.17.0.2:53715
3: I1114 02:01:01.833762 17260 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1375)@172.17.0.2:53715
3: I1114 02:01:01.834105 17264 slave.cpp:1161] Successfully authenticated with master master@172.17.0.2:53715
3: I1114 02:01:01.834398 17264 slave.cpp:1682] Will retry registration in 5.788704ms if necessary
3: I1114 02:01:01.834715 17257 master.cpp:6032] Received register agent message from slave(747)@172.17.0.2:53715 (a6ded58a1a6e)
3: I1114 02:01:01.834863 17257 master.cpp:3870] Authorizing agent with principal 'test-principal'
3: I1114 02:01:01.835331 17259 master.cpp:6092] Authorized registration of agent at slave(747)@172.17.0.2:53715 (a6ded58a1a6e)
3: I1114 02:01:01.835435 17259 master.cpp:6185] Registering agent at slave(747)@172.17.0.2:53715 (a6ded58a1a6e) with id 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-S0
3: I1114 02:01:01.835883 17263 registrar.cpp:495] Applied 1 operations in 68161ns; attempting to update the registry
3: I1114 02:01:01.836527 17272 registrar.cpp:552] Successfully updated the registry in 581888ns
3: I1114 02:01:01.836825 17272 master.cpp:6232] Admitted agent 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-S0 at slave(747)@172.17.0.2:53715 (a6ded58a1a6e)
3: I1114 02:01:01.837604 17265 slave.cpp:1207] Registered with master master@172.17.0.2:53715; given agent ID 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-S0
3: I1114 02:01:01.837715 17270 status_update_manager.cpp:184] Resuming sending status updates
3: I1114 02:01:01.837494 17272 master.cpp:6263] Registered agent 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-S0 at slave(747)@172.17.0.2:53715 (a6ded58a1a6e) 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:01:01.837890 17269 hierarchical.cpp:593] Added agent 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-S0 (a6ded58a1a6e) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
3: I1114 02:01:01.837958 17265 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_z64U8P/meta/slaves/8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-S0/slave.info'
3: I1114 02:01:01.838276 17269 hierarchical.cpp:1450] Performed allocation for 1 agents in 193300ns
3: I1114 02:01:01.838464 17265 slave.cpp:1285] Forwarding total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1114 02:01:01.838541 17265 slave.cpp:1295] Forwarding total oversubscribed resources {}
3: I1114 02:01:01.839097 17264 master.cpp:7051] Received update of agent 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-S0 at slave(747)@172.17.0.2:53715 (a6ded58a1a6e) with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1114 02:01:01.839169 17264 master.cpp:7064] Received update of agent 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-S0 at slave(747)@172.17.0.2:53715 (a6ded58a1a6e) with total oversubscribed resources {}
3: I1114 02:01:01.839424 17264 master.cpp:7082] Ignoring update on agent 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-S0 at slave(747)@172.17.0.2:53715 (a6ded58a1a6e) as it reports no changes
3: I1114 02:01:01.839648 17263 http_connection.hpp:221] New endpoint detected at http://172.17.0.2:53715/slave(747)/api/v1/resource_provider
3: I1114 02:01:01.841965 17272 http_connection.hpp:277] Connected with the remote endpoint at http://172.17.0.2:53715/slave(747)/api/v1/resource_provider
3: I1114 02:01:01.843013 17258 http_connection.hpp:129] Sending 1 call to http://172.17.0.2:53715/slave(747)/api/v1/resource_provider
3: I1114 02:01:01.844267 17269 process.cpp:3925] Handling HTTP event for process 'slave(747)' with path: '/slave(747)/api/v1/resource_provider'
3: I1114 02:01:01.845705 17267 http.cpp:1185] HTTP POST for /slave(747)/api/v1/resource_provider from 172.17.0.2:56906
3: I1114 02:01:01.846066 17268 manager.cpp:381] Subscribing resource provider {"name":"test","type":"org.apache.mesos.rp.test"}
3: I1114 02:01:01.849277 17258 http_connection.hpp:129] Sending 3 call to http://172.17.0.2:53715/slave(747)/api/v1/resource_provider
3: I1114 02:01:01.850370 17265 process.cpp:3925] Handling HTTP event for process 'slave(747)' with path: '/slave(747)/api/v1/resource_provider'
3: I1114 02:01:01.851675 17268 http.cpp:1185] HTTP POST for /slave(747)/api/v1/resource_provider from 172.17.0.2:56905
3: I1114 02:01:01.852248 17261 slave.cpp:6719] Handling resource provider message 'UPDATE_TOTAL_RESOURCES: f45a2961-f9ac-45f1-88c8-a6318b27879d disk[RAW]:200'
3: I1114 02:01:01.852370 17261 slave.cpp:6764] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200
3: I1114 02:01:01.853386 17263 master.cpp:7051] Received update of agent 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-S0 at slave(747)@172.17.0.2:53715 (a6ded58a1a6e) with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200
3: I1114 02:01:01.854590 17259 hierarchical.cpp:660] Agent 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-S0 (a6ded58a1a6e) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200
3: I1114 02:01:01.854619 17256 scheduler.cpp:190] Version: 1.5.0
3: I1114 02:01:01.854897 17259 hierarchical.cpp:1450] Performed allocation for 1 agents in 122003ns
3: I1114 02:01:01.854910 17268 scheduler.cpp:313] Using default 'basic' HTTP authenticatee
3: I1114 02:01:01.855370 17258 scheduler.cpp:496] New master detected at master@172.17.0.2:53715
3: I1114 02:01:01.855402 17258 scheduler.cpp:505] Waiting for 0ns before initiating a re-(connection) attempt with the master
3: I1114 02:01:01.858623 17266 scheduler.cpp:387] Connected with the master at http://172.17.0.2:53715/master/api/v1/scheduler
3: I1114 02:01:01.859894 17258 scheduler.cpp:249] Sending SUBSCRIBE call to http://172.17.0.2:53715/master/api/v1/scheduler
3: I1114 02:01:01.861559 17272 process.cpp:3925] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
3: I1114 02:01:01.862926 17268 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:56908
3: I1114 02:01:01.863277 17268 master.cpp:2613] Received subscription request for HTTP framework 'default'
3: I1114 02:01:01.863380 17268 master.cpp:2278] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
3: I1114 02:01:01.863924 17270 master.cpp:2748] Subscribing framework 'default' with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1114 02:01:01.864753 17265 hierarchical.cpp:303] Added framework 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-0000
3: I1114 02:01:01.865429 17258 scheduler.cpp:741] Enqueuing event SUBSCRIBED received from http://172.17.0.2:53715/master/api/v1/scheduler
3: I1114 02:01:01.865908 17258 scheduler.cpp:741] Enqueuing event HEARTBEAT received from http://172.17.0.2:53715/master/api/v1/scheduler
3: I1114 02:01:01.866704 17265 hierarchical.cpp:1450] Performed allocation for 1 agents in 1.760359ms
3: I1114 02:01:01.867451 17257 master.cpp:8115] Sending 1 offers to framework 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-0000 (default)
3: I1114 02:01:01.869711 17262 scheduler.cpp:741] Enqueuing event OFFERS received from http://172.17.0.2:53715/master/api/v1/scheduler
3: I1114 02:01:01.871686 17269 scheduler.cpp:249] Sending ACCEPT call to http://172.17.0.2:53715/master/api/v1/scheduler
3: I1114 02:01:01.873344 17272 process.cpp:3925] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
3: I1114 02:01:01.874742 17267 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:56907
3: I1114 02:01:01.875922 17267 master.cpp:10017] Removing offer 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-O0
3: I1114 02:01:01.876041 17267 master.cpp:4228] Processing ACCEPT call for offers: [ 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-O0 ] on agent 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-S0 at slave(747)@172.17.0.2:53715 (a6ded58a1a6e) for framework 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-0000 (default)
3: I1114 02:01:01.876880 17267 master.cpp:5293] Processing CREATE_BLOCK operation with source disk(allocated: *)[RAW]:200 from framework 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-0000 (default) to agent 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-S0 at slave(747)@172.17.0.2:53715 (a6ded58a1a6e)
3: I1114 02:01:01.877508 17267 master.cpp:9900] Sending offer operation Èf°xkýL*Œìxj^Ȝç to agent 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-S0 at slave(747)@172.17.0.2:53715 (a6ded58a1a6e)
3: I1114 02:01:01.878691 17269 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 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-S0 from framework 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-0000
3: I1114 02:01:01.878792 17269 hierarchical.cpp:1171] Framework 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-0000 filtered agent 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-S0 for 5secs
3: I1114 02:01:01.880939 17267 http_connection.hpp:129] Sending 2 call to http://172.17.0.2:53715/slave(747)/api/v1/resource_provider
3: I1114 02:01:01.882033 17265 process.cpp:3925] Handling HTTP event for process 'slave(747)' with path: '/slave(747)/api/v1/resource_provider'
3: I1114 02:01:01.922655 17262 http.cpp:1185] HTTP POST for /slave(747)/api/v1/resource_provider from 172.17.0.2:56905
3: I1114 02:01:01.923338 17259 slave.cpp:6719] Handling resource provider message 'UPDATE_OFFER_OPERATION_STATUS: {"framework_id":{"value":"8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-0000"},"status":{"state":"OFFER_OPERATION_FINISHED","converted_resources":[{"provider_id":{"value":"f45a2961-f9ac-45f1-88c8-a6318b27879d"},"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":"f45a2961-f9ac-45f1-88c8-a6318b27879d"},"name":"disk","type":"SCALAR","scalar":{"value":200.0},"allocation_info":{"role":"*"},"disk":{"source":{"type":"BLOCK"}}}]},"operation_uuid":"yGaweGv9TCqM7HhqXsic5w=="}'
3: I1114 02:01:01.923708 17259 slave.cpp:6893] Updating the state of offer operation '' (uuid: Èf°xkýL*Œìxj^Ȝç) of framework 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-0000 (latest state: OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED)
3: I1114 02:01:01.924041 17259 slave.cpp:6817] Forwarding status update of offer operation '' (uuid: c866b078-6bfd-4c2a-8cec-786a5ec89ce7) for framework 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-0000
3: I1114 02:01:01.924587 17263 master.cpp:9698] Updating the state of offer operation '' (uuid: c866b078-6bfd-4c2a-8cec-786a5ec89ce7) of framework 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-0000 (latest state: OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED)
3: F1114 02:01:01.925081 17263 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:     @     0x2b4eafe309fd  google::LogMessage::Fail()
3:     @     0x2b4eafe3289d  google::LogMessage::SendToLog()
3:     @     0x2b4eafe305ec  google::LogMessage::Flush()
3:     @     0x2b4eafe331be  google::LogMessageFatal::~LogMessageFatal()
3: I1114 02:01:01.928083 17267 hierarchical.cpp:849] Updated allocation of framework 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-0000 on agent 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-S0 from disk(allocated: *)[RAW]:200 to disk(allocated: *)[BLOCK]:200
3: I1114 02:01:01.928809 17267 hierarchical.cpp:1125] Recovered disk(allocated: *)[BLOCK]:200 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[BLOCK]:200, allocated: {}) on agent 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-S0 from framework 8aabf7e8-13a8-46a0-86d0-5d16c949fa8f-0000
3:     @           0xc93708  _CheckFatal::~_CheckFatal()
3: I1114 02:01:01.935128 17257 hierarchical.cpp:1450] Performed allocation for 1 agents in 1.115682ms
3:     @     0x2b4ead267e81  mesos::internal::master::Slave::apply()
3:     @     0x2b4ead25de44  mesos::internal::master::Master::updateOfferOperation()
3:     @     0x2b4ead248e57  mesos::internal::master::Master::offerOperationStatusUpdate()
3:     @     0x2b4ead2ad5f5  ProtobufProcess<>::_handlerM<>()
3:     @     0x2b4ead3675bf  _ZNSt5_BindIFPFvPN5mesos8internal6master6MasterEMS3_FvRKNS1_26OfferOperationStatusUpdateEERKN7process4UPIDERKSsES4_S9_St12_PlaceholderILi1EESI_ILi2EEEE6__callIvJSD_SF_EJLm0ELm1ELm2ELm3EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
3:     @     0x2b4ead3457f6  _ZNSt5_BindIFPFvPN5mesos8internal6master6MasterEMS3_FvRKNS1_26OfferOperationStatusUpdateEERKN7process4UPIDERKSsES4_S9_St12_PlaceholderILi1EESI_ILi2EEEEclIJSD_SF_EvEET0_DpOT_
3:     @     0x2b4ead31a732  std::_Function_handler<>::_M_invoke()
3:     @           0xe7ef5b  std::function<>::operator()()
3:     @     0x2b4ead28ebcc  ProtobufProcess<>::visit()
3:     @     0x2b4ead21ec70  mesos::internal::master::Master::_visit()
3:     @     0x2b4ead21e382  mesos::internal::master::Master::visit()
3:     @     0x2b4ead273a18  process::MessageEvent::visit()
3:     @           0xcad562  process::ProcessBase::serve()
3:     @     0x2b4eaf8df045  process::ProcessManager::resume()
3:     @     0x2b4eaf8db4a4  _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
3:     @     0x2b4eaf8ecf4c  _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
3:     @     0x2b4eaf8ecea3  _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
3:     @     0x2b4eaf8ece3c  _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
3:     @     0x2b4eb248aa60  (unknown)
3:     @     0x2b4eb005c184  start_thread
3:     @     0x2b4eb2cf6ffd  (unknown)
3/3 Test #3: MesosTests .......................***Exception: Other402.61 sec

67% tests passed, 1 tests failed out of 3

Total Test time (real) = 418.59 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]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1510621729-5490
Untagged: mesos-1510621729-5490:latest
Deleted: sha256:69007836f3473abb2c02ad14310df13c2ad857ce60132a4f05f8c92663f5ab6e
Deleted: sha256:463c325259e75c27ccdfafb1d8a7f64c6f655fbccc175f9e736d557344fa5b61
Deleted: sha256:e0ddab130494e0dbe9aaaaba73718f14b8fbed9424db46a9f969aaa2aa123bcd
Deleted: sha256:d36ee01d6d72bc0dea38e0f93d193c8d1f99bd63903451945bc62523867646af
Deleted: sha256:a1711ca8778f4f9844c22dccc3d234ba87b5ba2e63efa4465d50e27c3207a70c
Deleted: sha256:4f148940740d6a3ced08c0dd476dfb5c98191b449991d916a954cf1f155982ad
Deleted: sha256:425fc2a33c3c31b2070898f44d444d7dd257fb207e1a9a19970fff3e6b46f0e0
Deleted: sha256:363f356ab838188fc1a3dcf9e295420fe17e190b3ea3d314890a1f638651204a
Deleted: sha256:020041a62ac16c2564ce5ad270d706fe9d2c6f353a4ea4b19a95609f00c52d11
Deleted: sha256:a08a238000bbabd3567b8f137863ecb7b1f08093193c4f0aed6008ca25581f00
Deleted: sha256:ea90ac9273d78fac7171a9085846fa1eb29c18ea7eb87c68446f48f427b488b0
Deleted: sha256:07ca8097af078e322f00a33ceeb3eb84e2ab024d3d988eb9169876b4615256a3
Deleted: sha256:30c9d6cdb4cc72a621ed9b0987a0bd93501bb52dbd863da968f30707f69caa37
Deleted: sha256:73ebf26e84507ba05f88853ecfe66a4dc86f9a392a2c1900cb7986e556b8441b
Deleted: sha256:5f5a42080feeb52e8309e1501b370497b31001066943a651b6475bb10f2bf307
Deleted: sha256:c4b6610cc04df5c454176f80a1edf9cc132939406c12f192969533bad84a92fa
Deleted: sha256:955ea8105f42a8da3a4aa3b2a2c251020d531943a14212d9f7dbeef7c318f8c1
Deleted: sha256:4918b672ab4551072b749910320ba57608034e8f3eee68c3c25442939aed7252
Deleted: sha256:85ecd465fbc10d3c24e109e045fec05b263c614c874d047ce493d92e3ebea13d
Deleted: sha256:c45d9213e2c03c2cf813ee811353270ed38441fbe9a37d27bfed8723bf9f9d1e
Deleted: sha256:0ac5c32d4c79254033b162c7ec4a38d18d89fc2f787e618e987900b1617faf31
Deleted: sha256:63d1db84e8e7743c0af466f1b9fa35b14be543bb07dc79406ab9a88f7faf535c
Deleted: sha256:cdebaac3e7105dab67c76af60d7173e0b3ad6f283aeca8b5608012eaed62a529
Deleted: sha256:fa71cfbc3115b150033b7aa00a09c87ff7f189a1af6225dfb9e98c39b53e07c1
Deleted: sha256:2502ad592a712defb60bee475957447e5e013de3ddfe592ed5131d929d6630a9
Deleted: sha256:3b9e5a1f401110d4ab809e4aa7aea1fc5f678a5290ccd1b7651581d9e33ab75e
Deleted: sha256:74ef15310273f269d43137e895e758fdfc045d83c00f9f8009e1adc77cebebed
Deleted: sha256:c7e495343bdfda9af71f5a7bd782689b17657acd6c774be462a37e690e6fbcda
Deleted: sha256:49b96cc69556b9d47f132042399223f5c741aeb3fd0d1273d03c67c5cb6ce8f9
Deleted: sha256:7e50e0d7ca22e1e9e46b157637920f94efd2c9d3ab26ab26dbf134d264a9bcfa
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user songzihao1990@gmail.com
Not sending mail to unregistered user yujie.jay@gmail.com

Jenkins build is back to normal : Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4455

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,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)/4455/display/redirect?page=changes>


Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4452

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,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)/4452/display/redirect>

------------------------------------------
[...truncated 27.03 MB...]
3: I1114 02:49:59.070833 17265 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:49:59.071029 17265 slave.cpp:601] Agent attributes: [  ]
3: I1114 02:49:59.071043 17265 slave.cpp:610] Agent hostname: 0ab13b73ea95
3: I1114 02:49:59.071169 17260 status_update_manager.cpp:177] Pausing sending status updates
3: I1114 02:49:59.072695 17259 state.cpp:64] Recovering state from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_AgentEndpoint_1_pLfQ4l/meta'
3: I1114 02:49:59.073012 17270 status_update_manager.cpp:203] Recovering status update manager
3: I1114 02:49:59.073240 17266 containerizer.cpp:662] Recovering containerizer
3: I1114 02:49:59.074589 17259 provisioner.cpp:416] Provisioner recovery complete
3: I1114 02:49:59.074973 17272 slave.cpp:6432] Finished recovery
3: I1114 02:49:59.075659 17274 status_update_manager.cpp:177] Pausing sending status updates
3: I1114 02:49:59.075697 17261 slave.cpp:1007] New master detected at master@172.17.0.2:52022
3: I1114 02:49:59.075742 17261 slave.cpp:1042] Detecting new master
3: I1114 02:49:59.078347 17264 process.cpp:3925] Handling HTTP event for process 'slave(746)' with path: '/slave(746)/api/v1/resource_provider'
3: I1114 02:49:59.079666 17266 http.cpp:1185] HTTP POST for /slave(746)/api/v1/resource_provider from 172.17.0.2:37948
3: I1114 02:49:59.080098 17268 manager.cpp:381] Subscribing resource provider {"name":"test","type":"org.apache.mesos.rp.test"}
3: I1114 02:49:59.087663 17258 slave.cpp:883] Agent terminating
3: I1114 02:49:59.093164 17258 master.cpp:1151] Master terminating
3: [       OK ] ContentType/ResourceProviderManagerHttpApiTest.AgentEndpoint/1 (46 ms)
3: [ RUN      ] ContentType/ResourceProviderManagerHttpApiTest.ConvertResources/0
3: I1114 02:49:59.098561 17258 cluster.cpp:162] Creating default 'local' authorizer
3: I1114 02:49:59.100900 17267 master.cpp:448] Master 415a21b5-dbf4-4a77-b47d-923f9bfb4325 (0ab13b73ea95) started on 172.17.0.2:52022
3: I1114 02:49:59.100920 17267 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/6anY1r/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/6anY1r/master" --zk_session_timeout="10secs"
3: I1114 02:49:59.101193 17267 master.cpp:499] Master only allowing authenticated frameworks to register
3: I1114 02:49:59.101203 17267 master.cpp:505] Master only allowing authenticated agents to register
3: I1114 02:49:59.101210 17267 master.cpp:511] Master only allowing authenticated HTTP frameworks to register
3: I1114 02:49:59.101217 17267 credentials.hpp:37] Loading credentials for authentication from '/tmp/6anY1r/credentials'
3: I1114 02:49:59.101480 17267 master.cpp:555] Using default 'crammd5' authenticator
3: I1114 02:49:59.101617 17267 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1114 02:49:59.101760 17267 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1114 02:49:59.101879 17267 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1114 02:49:59.101992 17267 master.cpp:634] Authorization enabled
3: I1114 02:49:59.102133 17262 hierarchical.cpp:171] Initialized hierarchical allocator process
3: I1114 02:49:59.102166 17259 whitelist_watcher.cpp:77] No whitelist given
3: I1114 02:49:59.105226 17274 master.cpp:2213] Elected as the leading master!
3: I1114 02:49:59.105250 17274 master.cpp:1693] Recovering from registrar
3: I1114 02:49:59.105434 17266 registrar.cpp:347] Recovering registrar
3: I1114 02:49:59.105959 17266 registrar.cpp:391] Successfully fetched the registry (0B) in 489984ns
3: I1114 02:49:59.106061 17266 registrar.cpp:495] Applied 1 operations in 28896ns; attempting to update the registry
3: I1114 02:49:59.106540 17266 registrar.cpp:552] Successfully updated the registry in 430080ns
3: I1114 02:49:59.106637 17266 registrar.cpp:424] Successfully recovered registrar
3: I1114 02:49:59.106981 17264 master.cpp:1806] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I1114 02:49:59.107007 17269 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
3: W1114 02:49:59.110878 17258 process.cpp:3178] Attempted to spawn already running process files@172.17.0.2:52022
3: I1114 02:49:59.111666 17258 containerizer.cpp:301] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1114 02:49:59.112092 17258 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
3: W1114 02:49:59.112191 17258 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1114 02:49:59.112227 17258 provisioner.cpp:255] Using default backend 'copy'
3: I1114 02:49:59.113785 17258 cluster.cpp:448] Creating default 'local' authorizer
3: I1114 02:49:59.115594 17270 slave.cpp:262] Mesos agent started on (747)@172.17.0.2:52022
3: I1114 02:49:59.115613 17270 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_nNALub/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_nNALub/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_nNALub/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_nNALub/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_nNALub/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_nNALub" --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_WMpmLO" --zk_session_timeout="10secs"
3: I1114 02:49:59.116070 17270 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_nNALub/credential'
3: I1114 02:49:59.116194 17270 slave.cpp:295] Agent using credential for: test-principal
3: I1114 02:49:59.116210 17270 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_nNALub/http_credentials'
3: I1114 02:49:59.116396 17270 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1114 02:49:59.117677 17270 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:49:59.117867 17270 slave.cpp:601] Agent attributes: [  ]
3: I1114 02:49:59.117880 17270 slave.cpp:610] Agent hostname: 0ab13b73ea95
3: I1114 02:49:59.118021 17264 status_update_manager.cpp:177] Pausing sending status updates
3: I1114 02:49:59.119333 17259 state.cpp:64] Recovering state from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_WMpmLO/meta'
3: I1114 02:49:59.119632 17271 status_update_manager.cpp:203] Recovering status update manager
3: I1114 02:49:59.119812 17267 containerizer.cpp:662] Recovering containerizer
3: I1114 02:49:59.121074 17259 provisioner.cpp:416] Provisioner recovery complete
3: I1114 02:49:59.121433 17271 slave.cpp:6432] Finished recovery
3: I1114 02:49:59.122097 17269 status_update_manager.cpp:177] Pausing sending status updates
3: I1114 02:49:59.122125 17265 slave.cpp:1007] New master detected at master@172.17.0.2:52022
3: I1114 02:49:59.122167 17265 slave.cpp:1042] Detecting new master
3: I1114 02:49:59.127806 17267 slave.cpp:1069] Authenticating with master master@172.17.0.2:52022
3: I1114 02:49:59.127876 17267 slave.cpp:1078] Using default CRAM-MD5 authenticatee
3: I1114 02:49:59.128089 17274 authenticatee.cpp:121] Creating new client SASL connection
3: I1114 02:49:59.128353 17272 master.cpp:8285] Authenticating slave(747)@172.17.0.2:52022
3: I1114 02:49:59.128509 17260 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1376)@172.17.0.2:52022
3: I1114 02:49:59.128721 17268 authenticator.cpp:98] Creating new server SASL connection
3: I1114 02:49:59.128922 17261 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1114 02:49:59.128950 17261 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1114 02:49:59.129051 17261 authenticator.cpp:204] Received SASL authentication start
3: I1114 02:49:59.129149 17261 authenticator.cpp:326] Authentication requires more steps
3: I1114 02:49:59.129271 17262 authenticatee.cpp:259] Received SASL authentication step
3: I1114 02:49:59.129397 17259 authenticator.cpp:232] Received SASL authentication step
3: I1114 02:49:59.129426 17259 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0ab13b73ea95' server FQDN: '0ab13b73ea95' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1114 02:49:59.129441 17259 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1114 02:49:59.129482 17259 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1114 02:49:59.129513 17259 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0ab13b73ea95' server FQDN: '0ab13b73ea95' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1114 02:49:59.129523 17259 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1114 02:49:59.129526 17259 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1114 02:49:59.129539 17259 authenticator.cpp:318] Authentication success
3: I1114 02:49:59.129642 17273 authenticatee.cpp:299] Authentication success
3: I1114 02:49:59.129703 17263 master.cpp:8315] Successfully authenticated principal 'test-principal' at slave(747)@172.17.0.2:52022
3: I1114 02:49:59.129737 17271 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1376)@172.17.0.2:52022
3: I1114 02:49:59.129899 17266 slave.cpp:1161] Successfully authenticated with master master@172.17.0.2:52022
3: I1114 02:49:59.130146 17266 slave.cpp:1682] Will retry registration in 8.179456ms if necessary
3: I1114 02:49:59.130357 17274 master.cpp:6032] Received register agent message from slave(747)@172.17.0.2:52022 (0ab13b73ea95)
3: I1114 02:49:59.130484 17274 master.cpp:3870] Authorizing agent with principal 'test-principal'
3: I1114 02:49:59.130861 17260 master.cpp:6092] Authorized registration of agent at slave(747)@172.17.0.2:52022 (0ab13b73ea95)
3: I1114 02:49:59.130954 17260 master.cpp:6185] Registering agent at slave(747)@172.17.0.2:52022 (0ab13b73ea95) with id 415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0
3: I1114 02:49:59.131331 17268 registrar.cpp:495] Applied 1 operations in 57408ns; attempting to update the registry
3: I1114 02:49:59.131842 17268 registrar.cpp:552] Successfully updated the registry in 456960ns
3: I1114 02:49:59.132051 17273 master.cpp:6232] Admitted agent 415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 at slave(747)@172.17.0.2:52022 (0ab13b73ea95)
3: I1114 02:49:59.132740 17264 slave.cpp:1207] Registered with master master@172.17.0.2:52022; given agent ID 415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0
3: I1114 02:49:59.132622 17273 master.cpp:6263] Registered agent 415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 at slave(747)@172.17.0.2:52022 (0ab13b73ea95) 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:49:59.132828 17267 status_update_manager.cpp:184] Resuming sending status updates
3: I1114 02:49:59.132933 17265 hierarchical.cpp:593] Added agent 415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 (0ab13b73ea95) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
3: I1114 02:49:59.133008 17264 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_WMpmLO/meta/slaves/415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0/slave.info'
3: I1114 02:49:59.133200 17265 hierarchical.cpp:1450] Performed allocation for 1 agents in 137672ns
3: I1114 02:49:59.133371 17264 slave.cpp:1285] Forwarding total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1114 02:49:59.133424 17264 slave.cpp:1295] Forwarding total oversubscribed resources {}
3: I1114 02:49:59.133962 17271 master.cpp:7051] Received update of agent 415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 at slave(747)@172.17.0.2:52022 (0ab13b73ea95) with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1114 02:49:59.134016 17271 master.cpp:7064] Received update of agent 415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 at slave(747)@172.17.0.2:52022 (0ab13b73ea95) with total oversubscribed resources {}
3: I1114 02:49:59.134210 17271 master.cpp:7082] Ignoring update on agent 415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 at slave(747)@172.17.0.2:52022 (0ab13b73ea95) as it reports no changes
3: I1114 02:49:59.134333 17261 http_connection.hpp:221] New endpoint detected at http://172.17.0.2:52022/slave(747)/api/v1/resource_provider
3: I1114 02:49:59.136468 17273 http_connection.hpp:277] Connected with the remote endpoint at http://172.17.0.2:52022/slave(747)/api/v1/resource_provider
3: I1114 02:49:59.137356 17259 http_connection.hpp:129] Sending 1 call to http://172.17.0.2:52022/slave(747)/api/v1/resource_provider
3: I1114 02:49:59.138474 17265 process.cpp:3925] Handling HTTP event for process 'slave(747)' with path: '/slave(747)/api/v1/resource_provider'
3: I1114 02:49:59.139606 17262 http.cpp:1185] HTTP POST for /slave(747)/api/v1/resource_provider from 172.17.0.2:37950
3: I1114 02:49:59.139871 17262 manager.cpp:381] Subscribing resource provider {"name":"test","type":"org.apache.mesos.rp.test"}
3: I1114 02:49:59.142313 17272 http_connection.hpp:129] Sending 3 call to http://172.17.0.2:52022/slave(747)/api/v1/resource_provider
3: I1114 02:49:59.143250 17267 process.cpp:3925] Handling HTTP event for process 'slave(747)' with path: '/slave(747)/api/v1/resource_provider'
3: I1114 02:49:59.144232 17265 http.cpp:1185] HTTP POST for /slave(747)/api/v1/resource_provider from 172.17.0.2:37949
3: I1114 02:49:59.144609 17262 slave.cpp:6719] Handling resource provider message 'UPDATE_TOTAL_RESOURCES: 9438527e-51f8-4e13-ba7e-6f50d226f843 disk[RAW]:200'
3: I1114 02:49:59.144697 17262 slave.cpp:6764] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200
3: I1114 02:49:59.145385 17270 master.cpp:7051] Received update of agent 415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 at slave(747)@172.17.0.2:52022 (0ab13b73ea95) with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200
3: I1114 02:49:59.146420 17272 hierarchical.cpp:660] Agent 415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 (0ab13b73ea95) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200
3: I1114 02:49:59.146595 17258 scheduler.cpp:190] Version: 1.5.0
3: I1114 02:49:59.146659 17272 hierarchical.cpp:1450] Performed allocation for 1 agents in 107228ns
3: I1114 02:49:59.146855 17259 scheduler.cpp:313] Using default 'basic' HTTP authenticatee
3: I1114 02:49:59.147233 17265 scheduler.cpp:496] New master detected at master@172.17.0.2:52022
3: I1114 02:49:59.147259 17265 scheduler.cpp:505] Waiting for 0ns before initiating a re-(connection) attempt with the master
3: I1114 02:49:59.150328 17260 scheduler.cpp:387] Connected with the master at http://172.17.0.2:52022/master/api/v1/scheduler
3: I1114 02:49:59.151291 17267 scheduler.cpp:249] Sending SUBSCRIBE call to http://172.17.0.2:52022/master/api/v1/scheduler
3: I1114 02:49:59.152678 17261 process.cpp:3925] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
3: I1114 02:49:59.153842 17262 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:37952
3: I1114 02:49:59.154021 17262 master.cpp:2613] Received subscription request for HTTP framework 'default'
3: I1114 02:49:59.154116 17262 master.cpp:2278] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
3: I1114 02:49:59.154594 17269 master.cpp:2748] Subscribing framework 'default' with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1114 02:49:59.155303 17274 hierarchical.cpp:303] Added framework 415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000
3: I1114 02:49:59.155937 17264 scheduler.cpp:741] Enqueuing event SUBSCRIBED received from http://172.17.0.2:52022/master/api/v1/scheduler
3: I1114 02:49:59.156293 17264 scheduler.cpp:741] Enqueuing event HEARTBEAT received from http://172.17.0.2:52022/master/api/v1/scheduler
3: I1114 02:49:59.156646 17274 hierarchical.cpp:1450] Performed allocation for 1 agents in 1.175889ms
3: I1114 02:49:59.157196 17265 master.cpp:8115] Sending 1 offers to framework 415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000 (default)
3: I1114 02:49:59.158840 17270 scheduler.cpp:741] Enqueuing event OFFERS received from http://172.17.0.2:52022/master/api/v1/scheduler
3: I1114 02:49:59.160204 17273 scheduler.cpp:249] Sending ACCEPT call to http://172.17.0.2:52022/master/api/v1/scheduler
3: I1114 02:49:59.161542 17269 process.cpp:3925] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
3: I1114 02:49:59.162721 17268 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:37951
3: I1114 02:49:59.163583 17268 master.cpp:10017] Removing offer 415a21b5-dbf4-4a77-b47d-923f9bfb4325-O0
3: I1114 02:49:59.163677 17268 master.cpp:4228] Processing ACCEPT call for offers: [ 415a21b5-dbf4-4a77-b47d-923f9bfb4325-O0 ] on agent 415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 at slave(747)@172.17.0.2:52022 (0ab13b73ea95) for framework 415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000 (default)
3: I1114 02:49:59.164335 17268 master.cpp:5293] Processing CREATE_BLOCK operation with source disk(allocated: *)[RAW]:200 from framework 415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000 (default) to agent 415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 at slave(747)@172.17.0.2:52022 (0ab13b73ea95)
3: I1114 02:49:59.164806 17268 master.cpp:9900] Sending offer operation =ªnpöSOZ†¤Ð~€¼ to agent 415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 at slave(747)@172.17.0.2:52022 (0ab13b73ea95)
3: I1114 02:49:59.165954 17271 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 415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 from framework 415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000
3: I1114 02:49:59.166012 17271 hierarchical.cpp:1171] Framework 415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000 filtered agent 415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 for 5secs
3: I1114 02:49:59.167642 17267 http_connection.hpp:129] Sending 2 call to http://172.17.0.2:52022/slave(747)/api/v1/resource_provider
3: I1114 02:49:59.168624 17265 process.cpp:3925] Handling HTTP event for process 'slave(747)' with path: '/slave(747)/api/v1/resource_provider'
3: I1114 02:49:59.206424 17263 http.cpp:1185] HTTP POST for /slave(747)/api/v1/resource_provider from 172.17.0.2:37949
3: I1114 02:49:59.207088 17262 slave.cpp:6719] Handling resource provider message 'UPDATE_OFFER_OPERATION_STATUS: {"framework_id":{"value":"415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000"},"status":{"state":"OFFER_OPERATION_FINISHED","converted_resources":[{"provider_id":{"value":"9438527e-51f8-4e13-ba7e-6f50d226f843"},"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":"9438527e-51f8-4e13-ba7e-6f50d226f843"},"name":"disk","type":"SCALAR","scalar":{"value":200.0},"allocation_info":{"role":"*"},"disk":{"source":{"type":"BLOCK"}}}]},"operation_uuid":"PapucPZTT1qGpNB+D4ACvA=="}'
3: I1114 02:49:59.207437 17262 slave.cpp:6893] Updating the state of offer operation '' (uuid: =ªnpöSOZ†¤Ð~€¼) of framework 415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000 (latest state: OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED)
3: I1114 02:49:59.207761 17262 slave.cpp:6817] Forwarding status update of offer operation '' (uuid: 3daa6e70-f653-4f5a-86a4-d07e0f8002bc) for framework 415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000
3: I1114 02:49:59.208199 17266 master.cpp:9698] Updating the state of offer operation '' (uuid: 3daa6e70-f653-4f5a-86a4-d07e0f8002bc) of framework 415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000 (latest state: OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED)
3: F1114 02:49:59.208480 17266 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:     @     0x2abd940de9fd  google::LogMessage::Fail()
3:     @     0x2abd940e089d  google::LogMessage::SendToLog()
3:     @     0x2abd940de5ec  google::LogMessage::Flush()
3:     @     0x2abd940e11be  google::LogMessageFatal::~LogMessageFatal()
3: I1114 02:49:59.210330 17267 hierarchical.cpp:849] Updated allocation of framework 415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000 on agent 415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 from disk(allocated: *)[RAW]:200 to disk(allocated: *)[BLOCK]:200
3: I1114 02:49:59.210783 17267 hierarchical.cpp:1125] Recovered disk(allocated: *)[BLOCK]:200 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[BLOCK]:200, allocated: {}) on agent 415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 from framework 415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000
3: I1114 02:49:59.212476 17267 hierarchical.cpp:1450] Performed allocation for 1 agents in 1.469325ms
3:     @           0xc93708  _CheckFatal::~_CheckFatal()
3:     @     0x2abd91515e81  mesos::internal::master::Slave::apply()
3:     @     0x2abd9150be44  mesos::internal::master::Master::updateOfferOperation()
3:     @     0x2abd914f6e57  mesos::internal::master::Master::offerOperationStatusUpdate()
3:     @     0x2abd9155b5f5  ProtobufProcess<>::_handlerM<>()
3:     @     0x2abd916155bf  _ZNSt5_BindIFPFvPN5mesos8internal6master6MasterEMS3_FvRKNS1_26OfferOperationStatusUpdateEERKN7process4UPIDERKSsES4_S9_St12_PlaceholderILi1EESI_ILi2EEEE6__callIvJSD_SF_EJLm0ELm1ELm2ELm3EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
3:     @     0x2abd915f37f6  _ZNSt5_BindIFPFvPN5mesos8internal6master6MasterEMS3_FvRKNS1_26OfferOperationStatusUpdateEERKN7process4UPIDERKSsES4_S9_St12_PlaceholderILi1EESI_ILi2EEEEclIJSD_SF_EvEET0_DpOT_
3:     @     0x2abd915c8732  std::_Function_handler<>::_M_invoke()
3:     @           0xe7ef5b  std::function<>::operator()()
3:     @     0x2abd9153cbcc  ProtobufProcess<>::visit()
3:     @     0x2abd914ccc70  mesos::internal::master::Master::_visit()
3:     @     0x2abd914cc382  mesos::internal::master::Master::visit()
3:     @     0x2abd91521a18  process::MessageEvent::visit()
3:     @           0xcad562  process::ProcessBase::serve()
3:     @     0x2abd93b8d045  process::ProcessManager::resume()
3:     @     0x2abd93b894a4  _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
3:     @     0x2abd93b9af4c  _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
3:     @     0x2abd93b9aea3  _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
3:     @     0x2abd93b9ae3c  _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
3:     @     0x2abd96738a60  (unknown)
3:     @     0x2abd9430a184  start_thread
3:     @     0x2abd96fa4ffd  (unknown)
3/3 Test #3: MesosTests .......................***Exception: Other401.55 sec

67% tests passed, 1 tests failed out of 3

Total Test time (real) = 417.17 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-1510625238-10478
Untagged: mesos-1510625238-10478:latest
Deleted: sha256:95ef1fac99116998cffb8dba1e3c58dd4256594799f9947aacd304d121ee7c4e
Deleted: sha256:d59fc9374ded57c79e79479df1ebcc3b4117a5c895c8397e5d9f867a558379b6
Deleted: sha256:bb5929aa7b7c719762e2b2a54a8156cbb9ddbfdc2a40116398197776f9b6ad8e
Deleted: sha256:e28c5f4b78d192c6d3d4326a66c49c884e6cf310902334168474e2beae0dc5c7
Deleted: sha256:01b219ef7b974d0d964740ba286e5539c406cf8140e09b4acb1757364f6dcf46
Deleted: sha256:f3b23facba7eea89941a68707aadac6ddece6104980a6428ba3ac747cd7cef50
Deleted: sha256:e56fb2675f4802314918004f55d0f8e894031111ea9663f037aa3f52c5bbcd0c
Deleted: sha256:c53d1d883e64da0418265a47528fd9c9f52995c2d8b72a6c3f709124f09dcc49
Deleted: sha256:05cc1f257136419155cd881b8ae1000613a5609a2ad9ee927e4f4da30e2acb10
Deleted: sha256:c641086ae86bcb8a29f9a81fe37ad767b49ecf4fbfc3d34b8408033a543d3263
Deleted: sha256:dab3779963e07685418382c2710b342fe822dbeb46d8b385640134411da6f4d1
Deleted: sha256:85e9c82f223c83b504a6ba9c7efe0cd716fcb9eda0a6f8737d4251b2c18eeea5
Deleted: sha256:92db81a6937af34cb3e5f7ab7228ffe5f806d34e88ce8d4738ee93688917e806
Deleted: sha256:662480982581e252d05c57e1bd3642f88a5b7cdcde51fb7cbe698cc9ba53d1ec
Deleted: sha256:ef7ecb1deb59ec4b4f3dfe456bf2c8847d5c7d90c47d2d59ab8bead8babf1020
Deleted: sha256:71be62700c28c439959f20a9e4c48972734fd6c94418fd716fdaa40e3a04d02f
Deleted: sha256:fb4ad449fcc113f0afd01920ec2b121f6181ce16beae87c48adeb98cc0c50210
Deleted: sha256:8dd97b6174063b941d2bdecc3cc0e69f285d52e6443f13538ba6085a4eb4adcd
Deleted: sha256:b29680e24801a8bf320bc326ca053e004f4dea883a76eed16b6afced8f012d64
Deleted: sha256:97414470fe28fba488af9d294a0fbf91f89656067b2dcdf15e553fe192b8120b
Deleted: sha256:0498f7b41a2eb9abe1d2849a31732c628fe3b89b22a7682335942f45b7788128
Deleted: sha256:c58d0e37ef4e4866f0d01d8a9c3159d0a72b255a60daa8c707bff11da89b4c86
Deleted: sha256:b2973188ace4ce2c6110a41f767ea6899d705e1f606b0a30c786b4eecbdc7d68
Deleted: sha256:413c80f104a3924fbc0db9c6a5aac5243de88f695caacd91f7bcacc9c1898b44
Deleted: sha256:77dfabc72561b8519f775f95f3a7b4be81a54a6dd17789b87cb9f0d6673893a3
Deleted: sha256:5628289e5d96a69f80874f8718223c6c16ce8045ac53af48758e2138204edb8a
Deleted: sha256:6f44069db13a1d5171150700918e04f1406fa08ed2a89be7f832f8deab7f9c49
Deleted: sha256:374225c8938058578d2456e276b9946b5f2ca3c64c302b60b9d9c9904a3f770e
Deleted: sha256:c92a27d21fe4d1466978ed6cc7ff6bb1e57ffaaf0c9b016e25116fd27a3ba4f8
Deleted: sha256:d85552ebc9a27f4711da2463dad5643dd82af77805222057b0351bd57c42c2e8
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user songzihao1990@gmail.com
Not sending mail to unregistered user yujie.jay@gmail.com