[ https://issues.apache.org/jira/browse/MESOS-8263?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Jan Schlicht updated MESOS-8263: -------------------------------- Sprint: Mesosphere Sprint 68 Story Points: 2 Labels: mesosphere test (was: test) > ResourceProviderManagerHttpApiTest.ConvertResources is flaky > ------------------------------------------------------------ > > Key: MESOS-8263 > URL: https://issues.apache.org/jira/browse/MESOS-8263 > Project: Mesos > Issue Type: Bug > Components: flaky > Reporter: Jan Schlicht > Assignee: Jan Schlicht > Labels: mesosphere, test > > From a ASF CI run: > {noformat} > 3: [ OK ] > ContentType/ResourceProviderManagerHttpApiTest.ConvertResources/0 (1048 ms) > 3: [ RUN ] > ContentType/ResourceProviderManagerHttpApiTest.ConvertResources/1 > 3: I1123 08:06:04.233137 20036 cluster.cpp:162] Creating default 'local' > authorizer > 3: I1123 08:06:04.237293 20060 master.cpp:448] Master > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e (dce3e4c418cb) started on > 172.17.0.2:35090 > 3: I1123 08:06:04.237325 20060 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/EpiTO7/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/EpiTO7/master" > --zk_session_timeout="10secs" > 3: I1123 08:06:04.237727 20060 master.cpp:499] Master only allowing > authenticated frameworks to register > 3: I1123 08:06:04.237743 20060 master.cpp:505] Master only allowing > authenticated agents to register > 3: I1123 08:06:04.237753 20060 master.cpp:511] Master only allowing > authenticated HTTP frameworks to register > 3: I1123 08:06:04.237764 20060 credentials.hpp:37] Loading credentials for > authentication from '/tmp/EpiTO7/credentials' > 3: I1123 08:06:04.238149 20060 master.cpp:555] Using default 'crammd5' > authenticator > 3: I1123 08:06:04.238358 20060 http.cpp:1045] Creating default 'basic' HTTP > authenticator for realm 'mesos-master-readonly' > 3: I1123 08:06:04.238575 20060 http.cpp:1045] Creating default 'basic' HTTP > authenticator for realm 'mesos-master-readwrite' > 3: I1123 08:06:04.238764 20060 http.cpp:1045] Creating default 'basic' HTTP > authenticator for realm 'mesos-master-scheduler' > 3: I1123 08:06:04.238939 20060 master.cpp:634] Authorization enabled > 3: I1123 08:06:04.239159 20043 whitelist_watcher.cpp:77] No whitelist given > 3: I1123 08:06:04.239187 20045 hierarchical.cpp:173] Initialized hierarchical > allocator process > 3: I1123 08:06:04.242822 20041 master.cpp:2215] Elected as the leading master! > 3: I1123 08:06:04.242857 20041 master.cpp:1695] Recovering from registrar > 3: I1123 08:06:04.243067 20052 registrar.cpp:347] Recovering registrar > 3: I1123 08:06:04.243808 20052 registrar.cpp:391] Successfully fetched the > registry (0B) in 690944ns > 3: I1123 08:06:04.243953 20052 registrar.cpp:495] Applied 1 operations in > 37370ns; attempting to update the registry > 3: I1123 08:06:04.244638 20052 registrar.cpp:552] Successfully updated the > registry in 620032ns > 3: I1123 08:06:04.244798 20052 registrar.cpp:424] Successfully recovered > registrar > 3: I1123 08:06:04.245352 20058 hierarchical.cpp:211] Skipping recovery of > hierarchical allocator: nothing to recover > 3: I1123 08:06:04.245358 20057 master.cpp:1808] Recovered 0 agents from the > registry (129B); allowing 10mins for agents to re-register > 3: W1123 08:06:04.251852 20036 process.cpp:2756] Attempted to spawn already > running process files@172.17.0.2:35090 > 3: I1123 08:06:04.253250 20036 containerizer.cpp:301] Using isolation { > environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } > 3: W1123 08:06:04.253965 20036 backend.cpp:76] Failed to create 'aufs' > backend: AufsBackend requires root privileges > 3: W1123 08:06:04.254109 20036 backend.cpp:76] Failed to create 'bind' > backend: BindBackend requires root privileges > 3: I1123 08:06:04.254148 20036 provisioner.cpp:259] Using default backend > 'copy' > 3: I1123 08:06:04.256542 20036 cluster.cpp:448] Creating default 'local' > authorizer > 3: I1123 08:06:04.260066 20057 slave.cpp:262] Mesos agent started on > (784)@172.17.0.2:35090 > 3: I1123 08:06:04.260093 20057 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_1_Vr92Vg/store/appc" > --authenticate_http_executors="true" --authenticate_http_readonly="true" > --authenticate_http_readwrite="false" --authenticatee="crammd5" > --authentication_backoff_factor="1secs" --authorizer="local" > --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" > --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" > --cgroups_root="mesos" --container_disk_watch_interval="15secs" > --containerizers="mesos" > --credential="/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_1_Vr92Vg/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_1_Vr92Vg/store/docker" > --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" > --enforce_container_disk_quota="false" > --executor_registration_timeout="1mins" > --executor_reregistration_timeout="2secs" > --executor_secret_key="/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_1_Vr92Vg/executor_secret_key" > --executor_shutdown_grace_period="5secs" > --fetcher_cache_dir="/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_1_Vr92Vg/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_1_Vr92Vg/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_1_Vr92Vg" > --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_1_ycwsnc" > --zk_session_timeout="10secs" > 3: I1123 08:06:04.260721 20057 credentials.hpp:86] Loading credential for > authentication from > '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_1_Vr92Vg/credential' > 3: I1123 08:06:04.260936 20057 slave.cpp:295] Agent using credential for: > test-principal > 3: I1123 08:06:04.260975 20057 credentials.hpp:37] Loading credentials for > authentication from > '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_1_Vr92Vg/http_credentials' > 3: I1123 08:06:04.261390 20057 http.cpp:1045] Creating default 'basic' HTTP > authenticator for realm 'mesos-agent-executor' > 3: I1123 08:06:04.261538 20057 http.cpp:1066] Creating default 'jwt' HTTP > authenticator for realm 'mesos-agent-executor' > 3: I1123 08:06:04.261780 20057 http.cpp:1045] Creating default 'basic' HTTP > authenticator for realm 'mesos-agent-readonly' > 3: I1123 08:06:04.261898 20057 http.cpp:1066] Creating default 'jwt' HTTP > authenticator for realm 'mesos-agent-readonly' > 3: I1123 08:06:04.263828 20057 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: I1123 08:06:04.264111 20057 slave.cpp:601] Agent attributes: [ ] > 3: I1123 08:06:04.264124 20057 slave.cpp:610] Agent hostname: dce3e4c418cb > 3: I1123 08:06:04.264286 20054 task_status_update_manager.cpp:181] Pausing > sending task status updates > 3: I1123 08:06:04.266237 20052 state.cpp:64] Recovering state from > '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_1_ycwsnc/meta' > 3: I1123 08:06:04.266593 20052 task_status_update_manager.cpp:207] Recovering > task status update manager > 3: I1123 08:06:04.266840 20045 containerizer.cpp:668] Recovering containerizer > 3: I1123 08:06:04.268791 20048 provisioner.cpp:455] Provisioner recovery > complete > 3: I1123 08:06:04.269258 20054 slave.cpp:6493] Finished recovery > 3: I1123 08:06:04.270414 20057 task_status_update_manager.cpp:181] Pausing > sending task status updates > 3: I1123 08:06:04.270411 20055 slave.cpp:1007] New master detected at > master@172.17.0.2:35090 > 3: I1123 08:06:04.270539 20055 slave.cpp:1042] Detecting new master > 3: I1123 08:06:04.283004 20040 slave.cpp:1069] Authenticating with master > master@172.17.0.2:35090 > 3: I1123 08:06:04.283128 20040 slave.cpp:1078] Using default CRAM-MD5 > authenticatee > 3: I1123 08:06:04.283475 20042 authenticatee.cpp:121] Creating new client > SASL connection > 3: I1123 08:06:04.283859 20052 master.cpp:8312] Authenticating > slave(784)@172.17.0.2:35090 > 3: I1123 08:06:04.284049 20038 authenticator.cpp:414] Starting authentication > session for crammd5-authenticatee(1421)@172.17.0.2:35090 > 3: I1123 08:06:04.284358 20043 authenticator.cpp:98] Creating new server SASL > connection > 3: I1123 08:06:04.284647 20049 authenticatee.cpp:213] Received SASL > authentication mechanisms: CRAM-MD5 > 3: I1123 08:06:04.284683 20049 authenticatee.cpp:239] Attempting to > authenticate with mechanism 'CRAM-MD5' > 3: I1123 08:06:04.284826 20060 authenticator.cpp:204] Received SASL > authentication start > 3: I1123 08:06:04.284900 20060 authenticator.cpp:326] Authentication requires > more steps > 3: I1123 08:06:04.285058 20044 authenticatee.cpp:259] Received SASL > authentication step > 3: I1123 08:06:04.285233 20044 authenticator.cpp:232] Received SASL > authentication step > 3: I1123 08:06:04.285275 20044 auxprop.cpp:109] Request to lookup properties > for user: 'test-principal' realm: 'dce3e4c418cb' server FQDN: 'dce3e4c418cb' > SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false > SASL_AUXPROP_AUTHZID: false > 3: I1123 08:06:04.285287 20044 auxprop.cpp:181] Looking up auxiliary property > '*userPassword' > 3: I1123 08:06:04.285326 20044 auxprop.cpp:181] Looking up auxiliary property > '*cmusaslsecretCRAM-MD5' > 3: I1123 08:06:04.285348 20044 auxprop.cpp:109] Request to lookup properties > for user: 'test-principal' realm: 'dce3e4c418cb' server FQDN: 'dce3e4c418cb' > SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false > SASL_AUXPROP_AUTHZID: true > 3: I1123 08:06:04.285357 20044 auxprop.cpp:131] Skipping auxiliary property > '*userPassword' since SASL_AUXPROP_AUTHZID == true > 3: I1123 08:06:04.285363 20044 auxprop.cpp:131] Skipping auxiliary property > '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true > 3: I1123 08:06:04.285380 20044 authenticator.cpp:318] Authentication success > 3: I1123 08:06:04.285506 20059 authenticatee.cpp:299] Authentication success > 3: I1123 08:06:04.285557 20045 master.cpp:8342] Successfully authenticated > principal 'test-principal' at slave(784)@172.17.0.2:35090 > 3: I1123 08:06:04.285658 20047 authenticator.cpp:432] Authentication session > cleanup for crammd5-authenticatee(1421)@172.17.0.2:35090 > 3: I1123 08:06:04.285847 20044 slave.cpp:1161] Successfully authenticated > with master master@172.17.0.2:35090 > 3: I1123 08:06:04.286111 20044 slave.cpp:1685] Will retry registration in > 2.233007ms if necessary > 3: I1123 08:06:04.286402 20046 master.cpp:6036] Received register agent > message from slave(784)@172.17.0.2:35090 (dce3e4c418cb) > 3: I1123 08:06:04.286550 20046 master.cpp:3872] Authorizing agent with > principal 'test-principal' > 3: I1123 08:06:04.287089 20056 master.cpp:6098] Authorized registration of > agent at slave(784)@172.17.0.2:35090 (dce3e4c418cb) > 3: I1123 08:06:04.287220 20056 master.cpp:6191] Registering agent at > slave(784)@172.17.0.2:35090 (dce3e4c418cb) with id > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 > 3: I1123 08:06:04.287748 20053 registrar.cpp:495] Applied 1 operations in > 63340ns; attempting to update the registry > 3: I1123 08:06:04.288362 20053 registrar.cpp:552] Successfully updated the > registry in 548864ns > 3: I1123 08:06:04.288583 20042 master.cpp:6240] Admitted agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 at slave(784)@172.17.0.2:35090 > (dce3e4c418cb) > 3: I1123 08:06:04.289449 20042 master.cpp:6276] Registered agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 at slave(784)@172.17.0.2:35090 > (dce3e4c418cb) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] > 3: I1123 08:06:04.289716 20038 slave.cpp:1685] Will retry registration in > 35.387012ms if necessary > 3: I1123 08:06:04.289844 20043 hierarchical.cpp:600] Added agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 (dce3e4c418cb) with cpus:2; mem:1024; > disk:1024; ports:[31000-32000] (allocated: {}) > 3: I1123 08:06:04.289922 20038 slave.cpp:1207] Registered with master > master@172.17.0.2:35090; given agent ID > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 > 3: I1123 08:06:04.289942 20060 master.cpp:6036] Received register agent > message from slave(784)@172.17.0.2:35090 (dce3e4c418cb) > 3: I1123 08:06:04.290046 20060 master.cpp:3872] Authorizing agent with > principal 'test-principal' > 3: I1123 08:06:04.290077 20050 task_status_update_manager.cpp:188] Resuming > sending task status updates > 3: I1123 08:06:04.290155 20043 hierarchical.cpp:1457] Performed allocation > for 1 agents in 152178ns > 3: I1123 08:06:04.290329 20038 slave.cpp:1227] Checkpointing SlaveInfo to > '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_1_ycwsnc/meta/slaves/7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0/slave.info' > 3: I1123 08:06:04.290479 20059 master.cpp:6098] Authorized registration of > agent at slave(784)@172.17.0.2:35090 (dce3e4c418cb) > 3: I1123 08:06:04.290560 20059 master.cpp:6169] Agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 at slave(784)@172.17.0.2:35090 > (dce3e4c418cb) already registered, resending acknowledgement > 3: I1123 08:06:04.290829 20038 slave.cpp:1288] Forwarding total resources > cpus:2; mem:1024; disk:1024; ports:[31000-32000] > 3: I1123 08:06:04.290917 20038 slave.cpp:1298] Forwarding total > oversubscribed resources {} > 3: W1123 08:06:04.291487 20038 slave.cpp:1265] Already registered with master > master@172.17.0.2:35090 > 3: I1123 08:06:04.291539 20038 slave.cpp:1288] Forwarding total resources > cpus:2; mem:1024; disk:1024; ports:[31000-32000] > 3: I1123 08:06:04.291553 20037 master.cpp:7078] Received update of agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 at slave(784)@172.17.0.2:35090 > (dce3e4c418cb) with total resources cpus:2; mem:1024; disk:1024; > ports:[31000-32000] > 3: I1123 08:06:04.291610 20037 master.cpp:7091] Received update of agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 at slave(784)@172.17.0.2:35090 > (dce3e4c418cb) with total oversubscribed resources {} > 3: I1123 08:06:04.291649 20038 slave.cpp:1298] Forwarding total > oversubscribed resources {} > 3: I1123 08:06:04.291822 20037 master.cpp:7109] Ignoring update on agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 at slave(784)@172.17.0.2:35090 > (dce3e4c418cb) as it reports no changes > 3: I1123 08:06:04.292245 20037 master.cpp:7078] Received update of agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 at slave(784)@172.17.0.2:35090 > (dce3e4c418cb) with total resources cpus:2; mem:1024; disk:1024; > ports:[31000-32000] > 3: I1123 08:06:04.292304 20037 master.cpp:7091] Received update of agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 at slave(784)@172.17.0.2:35090 > (dce3e4c418cb) with total oversubscribed resources {} > 3: I1123 08:06:04.292381 20044 http_connection.hpp:221] New endpoint detected > at http://172.17.0.2:35090/slave(784)/api/v1/resource_provider > 3: I1123 08:06:04.292474 20037 master.cpp:7109] Ignoring update on agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 at slave(784)@172.17.0.2:35090 > (dce3e4c418cb) as it reports no changes > 3: I1123 08:06:04.292973 20036 scheduler.cpp:188] Version: 1.5.0 > 3: I1123 08:06:04.293253 20055 scheduler.cpp:311] Using default 'basic' HTTP > authenticatee > 3: I1123 08:06:04.293665 20049 scheduler.cpp:494] New master detected at > master@172.17.0.2:35090 > 3: I1123 08:06:04.293694 20049 scheduler.cpp:503] Waiting for 0ns before > initiating a re-(connection) attempt with the master > 3: I1123 08:06:04.294288 20045 http_connection.hpp:277] Connected with the > remote endpoint at http://172.17.0.2:35090/slave(784)/api/v1/resource_provider > 3: I1123 08:06:04.294934 20046 http_connection.hpp:129] Sending 1 call to > http://172.17.0.2:35090/slave(784)/api/v1/resource_provider > 3: I1123 08:06:04.295969 20055 scheduler.cpp:385] Connected with the master > at http://172.17.0.2:35090/master/api/v1/scheduler > 3: I1123 08:06:04.296830 20059 process.cpp:3503] Handling HTTP event for > process 'slave(784)' with path: '/slave(784)/api/v1/resource_provider' > 3: I1123 08:06:04.297196 20054 scheduler.cpp:247] Sending SUBSCRIBE call to > http://172.17.0.2:35090/master/api/v1/scheduler > 3: I1123 08:06:04.298148 20052 http.cpp:1185] HTTP POST for > /slave(784)/api/v1/resource_provider from 172.17.0.2:38204 > 3: I1123 08:06:04.298343 20052 process.cpp:3503] Handling HTTP event for > process 'master' with path: '/master/api/v1/scheduler' > 3: I1123 08:06:04.298552 20056 manager.cpp:386] Subscribing resource provider > {"name":"test","type":"org.apache.mesos.rp.test"} > 3: I1123 08:06:04.299580 20057 http.cpp:1185] HTTP POST for > /master/api/v1/scheduler from 172.17.0.2:38208 > 3: I1123 08:06:04.299873 20057 master.cpp:2615] Received subscription request > for HTTP framework 'default' > 3: I1123 08:06:04.300007 20057 master.cpp:2280] Authorizing framework > principal 'test-principal' to receive offers for roles '{ role }' > 3: I1123 08:06:04.300477 20050 master.cpp:2750] Subscribing framework > 'default' with checkpointing disabled and capabilities [ > RESERVATION_REFINEMENT ] > 3: I1123 08:06:04.300882 20056 http_connection.hpp:129] Sending 3 call to > http://172.17.0.2:35090/slave(784)/api/v1/resource_provider > 3: I1123 08:06:04.301465 20047 hierarchical.cpp:306] Added framework > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-0000 > 3: I1123 08:06:04.301954 20057 scheduler.cpp:739] Enqueuing event SUBSCRIBED > received from http://172.17.0.2:35090/master/api/v1/scheduler > 3: I1123 08:06:04.302021 20052 process.cpp:3503] Handling HTTP event for > process 'slave(784)' with path: '/slave(784)/api/v1/resource_provider' > 3: I1123 08:06:04.302435 20057 scheduler.cpp:739] Enqueuing event HEARTBEAT > received from http://172.17.0.2:35090/master/api/v1/scheduler > 3: I1123 08:06:04.303020 20055 http.cpp:1185] HTTP POST for > /slave(784)/api/v1/resource_provider from 172.17.0.2:38202 > 3: I1123 08:06:04.303211 20047 hierarchical.cpp:1457] Performed allocation > for 1 agents in 1.549319ms > 3: I1123 08:06:04.303767 20060 master.cpp:8142] Sending 1 offers to framework > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-0000 (default) > 3: I1123 08:06:04.303828 20050 slave.cpp:6780] Handling resource provider > message 'UPDATE_TOTAL_RESOURCES: 582a6138-5ac1-4c38-b407-576be39d0a82 > disk[RAW]:200' > 3: I1123 08:06:04.303941 20050 slave.cpp:6825] Forwarding new total resources > cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200 > 3: I1123 08:06:04.304744 20060 master.cpp:7078] Received update of agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 at slave(784)@172.17.0.2:35090 > (dce3e4c418cb) with total resources cpus:2; mem:1024; disk:1024; > ports:[31000-32000]; disk[RAW]:200 > 3: I1123 08:06:04.305366 20051 scheduler.cpp:739] Enqueuing event OFFERS > received from http://172.17.0.2:35090/master/api/v1/scheduler > 3: I1123 08:06:04.305364 20060 master.cpp:7141] Removing offer > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-O0 with resources cpus(allocated: > role):2; mem(allocated: role):1024; disk(allocated: role):1024; > ports(allocated: role):[31000-32000] on agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 at slave(784)@172.17.0.2:35090 > (dce3e4c418cb) > 3: I1123 08:06:04.306197 20060 master.cpp:10063] Removing offer > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-O0 > 3: I1123 08:06:04.306350 20058 hierarchical.cpp:667] Agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 (dce3e4c418cb) updated with total > resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200 > 3: /mesos/src/tests/resource_provider_manager_tests.cpp:713: Failure > 3: Value of: resources.empty() > 3: Actual: true > 3: Expected: false > 3: I1123 08:06:04.307636 20058 hierarchical.cpp:1132] Recovered > cpus(allocated: role):2; mem(allocated: role):1024; disk(allocated: > role):1024; ports(allocated: role):[31000-32000] (total: cpus:2; mem:1024; > disk:1024; ports:[31000-32000]; disk[RAW]:200, allocated: {}) on agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 from framework > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-0000 > 3: I1123 08:06:04.308148 20051 master.cpp:1425] Framework > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-0000 (default) disconnected > 3: I1123 08:06:04.308176 20051 master.cpp:3333] Deactivating framework > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-0000 (default) > 3: I1123 08:06:04.308253 20051 master.cpp:3310] Disconnecting framework > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-0000 (default) > 3: I1123 08:06:04.308284 20051 master.cpp:1440] Giving framework > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-0000 (default) 0ns to failover > 3: I1123 08:06:04.308549 20055 master.cpp:7974] Framework failover timeout, > removing framework 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-0000 (default) > 3: I1123 08:06:04.308575 20055 master.cpp:8831] Removing framework > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-0000 (default) > 3: I1123 08:06:04.308782 20038 slave.cpp:3270] Asked to shut down framework > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-0000 by master@172.17.0.2:35090 > 3: I1123 08:06:04.308811 20038 slave.cpp:3285] Cannot shut down unknown > framework 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-0000 > 3: W1123 08:06:04.309376 20037 master.cpp:7988] Master returning resources > offered to framework 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-0000 because the > framework has terminated or is inactive > 3: I1123 08:06:04.309382 20058 hierarchical.cpp:1457] Performed allocation > for 1 agents in 1.597792ms > 3: I1123 08:06:04.309464 20058 hierarchical.cpp:419] Deactivated framework > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-0000 > 3: I1123 08:06:04.310418 20058 hierarchical.cpp:358] Removed framework > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-0000 > 3: I1123 08:06:04.310714 20058 hierarchical.cpp:1132] Recovered > cpus(allocated: role):2; mem(allocated: role):1024; disk(allocated: > role):1024; ports(allocated: role):[31000-32000]; disk(allocated: > role)[RAW]:200 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; > disk[RAW]:200, allocated: {}) on agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 from framework > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-0000 > 3: I1123 08:06:04.311563 20054 slave.cpp:883] Agent terminating > 3: I1123 08:06:04.311776 20038 master.cpp:1311] Agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 at slave(784)@172.17.0.2:35090 > (dce3e4c418cb) disconnected > 3: I1123 08:06:04.311800 20038 master.cpp:3370] Disconnecting agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 at slave(784)@172.17.0.2:35090 > (dce3e4c418cb) > 3: I1123 08:06:04.311849 20038 master.cpp:3389] Deactivating agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 at slave(784)@172.17.0.2:35090 > (dce3e4c418cb) > 3: I1123 08:06:04.311949 20058 hierarchical.cpp:697] Agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 deactivated > 3: I1123 08:06:04.318100 20036 master.cpp:1153] Master terminating > 3: I1123 08:06:04.318877 20047 hierarchical.cpp:633] Removed agent > 7c9d8e8c-3fb3-44c5-8505-488ada3e848e-S0 > 3: [ FAILED ] > ContentType/ResourceProviderManagerHttpApiTest.ConvertResources/1, where > {noformat} -- This message was sent by Atlassian JIRA (v6.4.14#64029)