mesos-issues mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Benno Evers (JIRA)" <j...@apache.org>
Subject [jira] [Commented] (MESOS-9244) StorageLocalResourceProviderTest.AgentRegisteredWithNewId is flaky
Date Thu, 27 Sep 2018 11:44:00 GMT

    [ https://issues.apache.org/jira/browse/MESOS-9244?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=16630263#comment-16630263 ] 

Benno Evers commented on MESOS-9244:
------------------------------------

Observed failing again today (run 4498):
{noformat}
[ RUN      ] StorageLocalResourceProviderTest.AgentRegisteredWithNewId
I0927 00:47:50.395409 24729 cluster.cpp:173] Creating default 'local' authorizer
I0927 00:47:50.396735 15384 master.cpp:413] Master da47bd11-4a8b-4460-9c85-28d44dfec556 (ip-172-16-10-152.ec2.internal) started on 172.16.10.152:43224
I0927 00:47:50.396757 15384 master.cpp:416] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="50ms" --allocator="hierarchical" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authentication_v0_timeout="15secs" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/MzmYEU/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" --memory_profiling="false" --min_allocatable_resources="cpus:0.01|mem:32" --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" --require_agent_domain="false" --role_sorter="drf" --root_submissions="true" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/MzmYEU/master" --zk_session_timeout="10secs"
I0927 00:47:50.396898 15384 master.cpp:465] Master only allowing authenticated frameworks to register
I0927 00:47:50.396906 15384 master.cpp:471] Master only allowing authenticated agents to register
I0927 00:47:50.396911 15384 master.cpp:477] Master only allowing authenticated HTTP frameworks to register
I0927 00:47:50.396917 15384 credentials.hpp:37] Loading credentials for authentication from '/tmp/MzmYEU/credentials'
I0927 00:47:50.396997 15384 master.cpp:521] Using default 'crammd5' authenticator
I0927 00:47:50.397044 15384 http.cpp:1037] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0927 00:47:50.397109 15384 http.cpp:1037] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0927 00:47:50.397137 15384 http.cpp:1037] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0927 00:47:50.397157 15384 master.cpp:602] Authorization enabled
I0927 00:47:50.397276 15380 hierarchical.cpp:182] Initialized hierarchical allocator process
I0927 00:47:50.397390 15381 whitelist_watcher.cpp:77] No whitelist given
I0927 00:47:50.397969 15383 master.cpp:2083] Elected as the leading master!
I0927 00:47:50.397987 15383 master.cpp:1638] Recovering from registrar
I0927 00:47:50.398027 15383 registrar.cpp:339] Recovering registrar
I0927 00:47:50.398197 15383 registrar.cpp:383] Successfully fetched the registry (0B) in 156928ns
I0927 00:47:50.398234 15383 registrar.cpp:487] Applied 1 operations in 7783ns; attempting to update the registry
I0927 00:47:50.398360 15380 registrar.cpp:544] Successfully updated the registry in 110848ns
I0927 00:47:50.398397 15380 registrar.cpp:416] Successfully recovered registrar
I0927 00:47:50.398492 15380 master.cpp:1752] Recovered 0 agents from the registry (176B); allowing 10mins for agents to reregister
I0927 00:47:50.398603 15384 hierarchical.cpp:220] Skipping recovery of hierarchical allocator: nothing to recover
W0927 00:47:50.400257 24729 process.cpp:2810] Attempted to spawn already running process files@172.16.10.152:43224
I0927 00:47:50.400712 24729 containerizer.cpp:305] Using isolation { cgroups/cpu, filesystem/posix, environment_secret, cgroups/mem, network/cni }
I0927 00:47:50.418900 24729 linux_launcher.cpp:144] Using /sys/fs/cgroup/freezer as the freezer hierarchy for the Linux launcher
I0927 00:47:50.419366 24729 provisioner.cpp:298] Using default backend 'aufs'
I0927 00:47:50.425318 24729 cluster.cpp:485] Creating default 'local' authorizer
I0927 00:47:50.425871 15381 slave.cpp:267] Mesos agent started on (755)@172.16.10.152:43224
I0927 00:47:50.425886 15381 slave.cpp:268] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up/store/appc" --authenticate_http_executors="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authentication_timeout_max="1mins" --authentication_timeout_min="5secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_destroy_timeout="1mins" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos_test_cf489fc8-f42c-44dc-8c3a-c44fb4d84a82" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_profile_adaptor="org_apache_mesos_UriDiskProfileAdaptor" --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/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up/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/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --gc_non_executor_container_sandboxes="false" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="cgroups/cpu,cgroups/mem" --jwt_secret_key="/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up/jwt_secret_key" --launcher="linux" --launcher_dir="/home/ubuntu/workspace/mesos/Mesos_CI-build/FLAG/SSL/label/mesos-ec2-ubuntu-14.04/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --memory_profiling="false" --network_cni_metrics="true" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resource_provider_config_dir="/tmp/MzmYEU/resource_provider_configs" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up" --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/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_irDZ49" --zk_session_timeout="10secs"
I0927 00:47:50.426095 15381 credentials.hpp:86] Loading credential for authentication from '/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up/credential'
I0927 00:47:50.426162 15381 slave.cpp:300] Agent using credential for: test-principal
I0927 00:47:50.426174 15381 credentials.hpp:37] Loading credentials for authentication from '/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up/http_credentials'
I0927 00:47:50.426295 15381 http.cpp:1037] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0927 00:47:50.426331 15381 http.cpp:1058] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0927 00:47:50.426371 15381 http.cpp:1037] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0927 00:47:50.426389 15381 http.cpp:1058] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0927 00:47:50.426420 15381 http.cpp:1037] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0927 00:47:50.426440 15381 http.cpp:1058] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0927 00:47:50.426496 15381 disk_profile_adaptor.cpp:84] Creating disk profile adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I0927 00:47:50.426914 15382 uri_disk_profile_adaptor.cpp:304] Updated disk profile mapping to 1 active profiles
I0927 00:47:50.427207 15381 slave.cpp:615] 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"}]
I0927 00:47:50.427264 15381 slave.cpp:623] Agent attributes: [  ]
I0927 00:47:50.427271 15381 slave.cpp:632] Agent hostname: ip-172-16-10-152.ec2.internal
I0927 00:47:50.427407 15380 task_status_update_manager.cpp:181] Pausing sending task status updates
I0927 00:47:50.427525 15381 state.cpp:66] Recovering state from '/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_irDZ49/meta'
I0927 00:47:50.427575 15381 slave.cpp:6909] Finished recovering checkpointed state from '/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_irDZ49/meta', beginning agent recovery
I0927 00:47:50.427613 15381 task_status_update_manager.cpp:207] Recovering task status update manager
I0927 00:47:50.427688 15379 containerizer.cpp:727] Recovering Mesos containers
I0927 00:47:50.427743 15379 linux_launcher.cpp:286] Recovering Linux launcher
I0927 00:47:50.427881 15379 containerizer.cpp:1053] Recovering isolators
I0927 00:47:50.428227 15377 containerizer.cpp:1092] Recovering provisioner
I0927 00:47:50.428354 15377 provisioner.cpp:494] Provisioner recovery complete
I0927 00:47:50.428517 15377 composing.cpp:339] Finished recovering all containerizers
I0927 00:47:50.428568 15384 slave.cpp:7138] Recovering executors
I0927 00:47:50.428594 15384 slave.cpp:7291] Finished recovery
I0927 00:47:50.428861 15382 task_status_update_manager.cpp:181] Pausing sending task status updates
I0927 00:47:50.428876 15379 slave.cpp:1254] New master detected at master@172.16.10.152:43224
I0927 00:47:50.428897 15379 slave.cpp:1319] Detecting new master
I0927 00:47:50.436020 15383 slave.cpp:1346] Authenticating with master master@172.16.10.152:43224
I0927 00:47:50.436048 15383 slave.cpp:1355] Using default CRAM-MD5 authenticatee
I0927 00:47:50.436120 15384 authenticatee.cpp:121] Creating new client SASL connection
I0927 00:47:50.436581 15384 master.cpp:9653] Authenticating slave(755)@172.16.10.152:43224
I0927 00:47:50.436632 15384 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1465)@172.16.10.152:43224
I0927 00:47:50.436704 15384 authenticator.cpp:98] Creating new server SASL connection
I0927 00:47:50.437078 15384 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0927 00:47:50.437098 15384 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0927 00:47:50.437129 15384 authenticator.cpp:204] Received SASL authentication start
I0927 00:47:50.437178 15384 authenticator.cpp:326] Authentication requires more steps
I0927 00:47:50.437211 15384 authenticatee.cpp:259] Received SASL authentication step
I0927 00:47:50.437260 15379 authenticator.cpp:232] Received SASL authentication step
I0927 00:47:50.437276 15379 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ip-172-16-10-152.ec2.internal' server FQDN: 'ip-172-16-10-152.ec2.internal' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0927 00:47:50.437283 15379 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0927 00:47:50.437289 15379 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0927 00:47:50.437294 15379 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ip-172-16-10-152.ec2.internal' server FQDN: 'ip-172-16-10-152.ec2.internal' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0927 00:47:50.437299 15379 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0927 00:47:50.437304 15379 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0927 00:47:50.437311 15379 authenticator.cpp:318] Authentication success
I0927 00:47:50.437360 15377 authenticatee.cpp:299] Authentication success
I0927 00:47:50.437433 15377 slave.cpp:1446] Successfully authenticated with master master@172.16.10.152:43224
I0927 00:47:50.437466 15380 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1465)@172.16.10.152:43224
I0927 00:47:50.437546 15377 slave.cpp:1877] Will retry registration in 14.03619ms if necessary
I0927 00:47:50.437558 15379 master.cpp:9685] Successfully authenticated principal 'test-principal' at slave(755)@172.16.10.152:43224
I0927 00:47:50.437605 15379 master.cpp:6605] Received register agent message from slave(755)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal)
I0927 00:47:50.437654 15379 master.cpp:3964] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal'
I0927 00:47:50.437783 15382 master.cpp:6672] Authorized registration of agent at slave(755)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal)
I0927 00:47:50.437834 15382 master.cpp:6787] Registering agent at slave(755)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal) with id da47bd11-4a8b-4460-9c85-28d44dfec556-S0
I0927 00:47:50.437973 15382 registrar.cpp:487] Applied 1 operations in 41824ns; attempting to update the registry
I0927 00:47:50.438225 15378 registrar.cpp:544] Successfully updated the registry in 166144ns
I0927 00:47:50.438285 15378 master.cpp:6835] Admitted agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 at slave(755)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal)
I0927 00:47:50.438402 15378 master.cpp:6880] Registered agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 at slave(755)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0927 00:47:50.438459 15383 hierarchical.cpp:601] Added agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 (ip-172-16-10-152.ec2.internal) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0927 00:47:50.438506 15378 slave.cpp:1479] Registered with master master@172.16.10.152:43224; given agent ID da47bd11-4a8b-4460-9c85-28d44dfec556-S0
I0927 00:47:50.438551 15380 task_status_update_manager.cpp:188] Resuming sending task status updates
I0927 00:47:50.438602 15383 hierarchical.cpp:1564] Performed allocation for 1 agents in 14717ns
I0927 00:47:50.438644 15378 slave.cpp:1499] Checkpointing SlaveInfo to '/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_irDZ49/meta/slaves/da47bd11-4a8b-4460-9c85-28d44dfec556-S0/slave.info'
I0927 00:47:50.438935 15378 slave.cpp:1548] Forwarding agent update {"operations":{},"resource_version_uuid":{"value":"TRhxfYHNQ1agzGwHv0LxKw=="},"slave_id":{"value":"da47bd11-4a8b-4460-9c85-28d44dfec556-S0"},"update_oversubscribed_resources":false}
I0927 00:47:50.439229 15377 master.cpp:7939] Ignoring update on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 at slave(755)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal) as it reports no changes
I0927 00:47:50.440562 15381 process.cpp:3569] Handling HTTP event for process 'slave(755)' with path: '/slave(755)/api/v1'
W0927 00:47:50.440893 24729 process.cpp:2810] Attempted to spawn already running process version@172.16.10.152:43224
I0927 00:47:50.441020 15383 http.cpp:1177] HTTP POST for /slave(755)/api/v1 from 172.16.10.152:39878
I0927 00:47:50.441115 15383 http.cpp:2074] Processing GET_CONTAINERS call
I0927 00:47:50.442292 24729 sched.cpp:232] Version: 1.8.0
I0927 00:47:50.442665 15379 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE'
I0927 00:47:50.442713 15381 sched.cpp:336] New master detected at master@172.16.10.152:43224
I0927 00:47:50.442739 15381 sched.cpp:401] Authenticating with master master@172.16.10.152:43224
I0927 00:47:50.442746 15381 sched.cpp:408] Using default CRAM-MD5 authenticatee
I0927 00:47:50.442826 15383 authenticatee.cpp:121] Creating new client SASL connection
I0927 00:47:50.443228 15383 master.cpp:9653] Authenticating scheduler-e1c32440-da1c-4f02-b51c-5f6bbf19cc03@172.16.10.152:43224
I0927 00:47:50.443264 15381 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1466)@172.16.10.152:43224
I0927 00:47:50.443317 15381 authenticator.cpp:98] Creating new server SASL connection
I0927 00:47:50.443419 15383 process.cpp:3569] Handling HTTP event for process 'slave(755)' with path: '/slave(755)/api/v1'
I0927 00:47:50.443722 15381 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0927 00:47:50.443740 15381 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0927 00:47:50.443764 15381 authenticator.cpp:204] Received SASL authentication start
I0927 00:47:50.443794 15381 authenticator.cpp:326] Authentication requires more steps
I0927 00:47:50.443821 15381 authenticatee.cpp:259] Received SASL authentication step
I0927 00:47:50.443851 15381 authenticator.cpp:232] Received SASL authentication step
I0927 00:47:50.443864 15381 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ip-172-16-10-152.ec2.internal' server FQDN: 'ip-172-16-10-152.ec2.internal' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0927 00:47:50.443871 15381 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0927 00:47:50.443881 15381 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0927 00:47:50.443888 15381 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ip-172-16-10-152.ec2.internal' server FQDN: 'ip-172-16-10-152.ec2.internal' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0927 00:47:50.443895 15381 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0927 00:47:50.443902 15381 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0927 00:47:50.443912 15381 authenticator.cpp:318] Authentication success
I0927 00:47:50.443919 15383 http.cpp:1177] HTTP POST for /slave(755)/api/v1 from 172.16.10.152:39879
I0927 00:47:50.443939 15381 authenticatee.cpp:299] Authentication success
I0927 00:47:50.444025 15384 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1466)@172.16.10.152:43224
I0927 00:47:50.444082 15384 sched.cpp:513] Successfully authenticated with master master@172.16.10.152:43224
I0927 00:47:50.444097 15384 sched.cpp:817] Sending SUBSCRIBE call to master@172.16.10.152:43224
I0927 00:47:50.444129 15384 sched.cpp:850] Will retry registration in 974.040281ms if necessary
I0927 00:47:50.444082 15383 http.cpp:2440] Processing LAUNCH_CONTAINER call for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE'
I0927 00:47:50.444387 15383 http.cpp:2556] Creating sandbox '/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_irDZ49/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE'
I0927 00:47:50.444396 15381 master.cpp:9685] Successfully authenticated principal 'test-principal' at scheduler-e1c32440-da1c-4f02-b51c-5f6bbf19cc03@172.16.10.152:43224
I0927 00:47:50.444453 15381 master.cpp:2854] Received SUBSCRIBE call for framework 'default' at scheduler-e1c32440-da1c-4f02-b51c-5f6bbf19cc03@172.16.10.152:43224
I0927 00:47:50.444471 15381 master.cpp:2155] Authorizing framework principal 'test-principal' to receive offers for roles '{ storage }'
I0927 00:47:50.444563 15381 containerizer.cpp:1280] Starting container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:47:50.444569 15383 master.cpp:2935] Subscribing framework default with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
I0927 00:47:50.444938 15381 containerizer.cpp:1446] Checkpointed ContainerConfig at '/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE/config'
I0927 00:47:50.444952 15381 containerizer.cpp:3118] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE from PROVISIONING to PREPARING
I0927 00:47:50.444998 15383 master.cpp:9883] Adding framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 (default) at scheduler-e1c32440-da1c-4f02-b51c-5f6bbf19cc03@172.16.10.152:43224 with roles {  } suppressed
I0927 00:47:50.445094 15383 cgroups.cpp:429] Creating cgroup at '/sys/fs/cgroup/cpu/mesos_test_cf489fc8-f42c-44dc-8c3a-c44fb4d84a82/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE' for container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:47:50.445348 15384 hierarchical.cpp:306] Added framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:50.445524 15383 cgroups.cpp:429] Creating cgroup at '/sys/fs/cgroup/cpuacct/mesos_test_cf489fc8-f42c-44dc-8c3a-c44fb4d84a82/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE' for container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:47:50.445606 15384 hierarchical.cpp:1564] Performed allocation for 1 agents in 217887ns
I0927 00:47:50.445657 15384 sched.cpp:744] Framework registered with da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:50.445675 15384 sched.cpp:758] Scheduler::registered took 8674ns
I0927 00:47:50.445789 15384 master.cpp:9468] Sending offers [ da47bd11-4a8b-4460-9c85-28d44dfec556-O0 ] to framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 (default) at scheduler-e1c32440-da1c-4f02-b51c-5f6bbf19cc03@172.16.10.152:43224
I0927 00:47:50.445875 15383 cgroups.cpp:429] Creating cgroup at '/sys/fs/cgroup/memory/mesos_test_cf489fc8-f42c-44dc-8c3a-c44fb4d84a82/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE' for container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:47:50.445916 15384 sched.cpp:914] Scheduler::resourceOffers took 24990ns
I0927 00:47:50.446216 15384 master.cpp:5850] Processing DECLINE call for offers: [ da47bd11-4a8b-4460-9c85-28d44dfec556-O0 ] for framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 (default) at scheduler-e1c32440-da1c-4f02-b51c-5f6bbf19cc03@172.16.10.152:43224
I0927 00:47:50.446293 15384 master.cpp:11462] Removing offer da47bd11-4a8b-4460-9c85-28d44dfec556-O0
I0927 00:47:50.446395 15384 hierarchical.cpp:1236] Recovered cpus(allocated: storage):2; mem(allocated: storage):1024; disk(allocated: storage):1024; ports(allocated: storage):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 from framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:50.446429 15384 hierarchical.cpp:1282] Framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 filtered agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 for 365days
I0927 00:47:50.446413 15383 memory.cpp:478] Started listening for OOM events for container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:47:50.446643 15383 memory.cpp:590] Started listening on 'low' memory pressure events for container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:47:50.446676 15383 memory.cpp:590] Started listening on 'medium' memory pressure events for container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:47:50.446703 15383 memory.cpp:590] Started listening on 'critical' memory pressure events for container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:47:50.447010 15379 memory.cpp:198] Updated 'memory.soft_limit_in_bytes' to 1GB for container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:47:50.447018 15384 cpu.cpp:92] Updated 'cpu.shares' to 102 (cpus 0.1) for container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:47:50.447096 15379 memory.cpp:227] Updated 'memory.limit_in_bytes' to 1GB for container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:47:50.447594 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:50.447629 15377 hierarchical.cpp:1564] Performed allocation for 1 agents in 84698ns
I0927 00:47:50.448082 15379 containerizer.cpp:1939] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["/home/ubuntu/workspace/mesos/Mesos_CI-build/FLAG/SSL/label/mesos-ec2-ubuntu-14.04/mesos/build/src/test-csi-plugin","--available_capacity=4GB","--volumes=","--work_dir=/tmp/MzmYEU/test_csi_plugin"],"shell":false,"value":"/home/ubuntu/workspace/mesos/Mesos_CI-build/FLAG/SSL/label/mesos-ec2-ubuntu-14.04/mesos/build/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_irDZ49/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE"},{"name":"CSI_ENDPOINT","type":"VALUE","value":"unix:///tmp/mesos-csi-sSV3yp/endpoint.sock"}]},"task_environment":{},"working_directory":"/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_irDZ49/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE"}" --pipe_read="71" --pipe_write="73" --runtime_directory="/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE" --unshare_namespace_mnt="false"'
I0927 00:47:50.448267 15378 linux_launcher.cpp:492] Launching container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE and cloning with namespaces 
I0927 00:47:50.452211 15379 containerizer.cpp:3118] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE from PREPARING to ISOLATING
I0927 00:47:50.455379 15384 containerizer.cpp:3118] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE from ISOLATING to FETCHING
I0927 00:47:50.455838 15382 fetcher.cpp:369] Starting to fetch URIs for container: org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE, directory: /tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_irDZ49/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:47:50.456413 15382 containerizer.cpp:3118] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE from FETCHING to RUNNING
I0927 00:47:50.458482 15384 container_daemon.cpp:140] Invoking post-start hook for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE'
I0927 00:47:50.498499 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:50.498575 15382 hierarchical.cpp:1564] Performed allocation for 1 agents in 184757ns
I0927 00:47:50.548990 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:50.549305 15378 hierarchical.cpp:1564] Performed allocation for 1 agents in 426903ns
I0927 00:47:50.583292 18982 test_csi_plugin.cpp:302] ProbeRequest '{}'
I0927 00:47:50.583797 15378 container_daemon.cpp:171] Waiting for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE'
I0927 00:47:50.584339 18982 test_csi_plugin.cpp:274] GetPluginInfoRequest '{}'
I0927 00:47:50.584646 15379 process.cpp:3569] Handling HTTP event for process 'slave(755)' with path: '/slave(755)/api/v1'
I0927 00:47:50.584851 15377 provider.cpp:2178] Node plugin loaded: {"name":"org.apache.mesos.csi.test","vendorVersion":"1.8.0"}
I0927 00:47:50.585319 18982 test_csi_plugin.cpp:288] GetPluginCapabilitiesRequest '{}'
I0927 00:47:50.585363 15382 http.cpp:1177] HTTP POST for /slave(755)/api/v1 from 172.16.10.152:39880
I0927 00:47:50.585458 15382 http.cpp:2670] Processing WAIT_CONTAINER call for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE'
I0927 00:47:50.585883 18983 test_csi_plugin.cpp:274] GetPluginInfoRequest '{}'
I0927 00:47:50.586159 15382 provider.cpp:2219] Controller plugin loaded: {"name":"org.apache.mesos.csi.test","vendorVersion":"1.8.0"}
I0927 00:47:50.586444 18984 test_csi_plugin.cpp:593] ControllerGetCapabilitiesRequest '{}'
I0927 00:47:50.586882 18982 test_csi_plugin.cpp:870] NodeGetCapabilitiesRequest '{}'
I0927 00:47:50.587323 18982 test_csi_plugin.cpp:857] NodeGetIdRequest '{}'
I0927 00:47:50.587666 15384 provider.cpp:661] Finished recovery for resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test
I0927 00:47:50.587743 15384 status_update_manager_process.hpp:379] Pausing operation status update manager
I0927 00:47:50.587795 15384 http_connection.hpp:227] New endpoint detected at http://172.16.10.152:43224/slave(755)/api/v1/resource_provider
I0927 00:47:50.588270 15380 http_connection.hpp:283] Connected with the remote endpoint at http://172.16.10.152:43224/slave(755)/api/v1/resource_provider
I0927 00:47:50.588388 15382 provider.cpp:524] Connected to resource provider manager
I0927 00:47:50.588563 15383 http_connection.hpp:131] Sending 1 call to http://172.16.10.152:43224/slave(755)/api/v1/resource_provider
I0927 00:47:50.588835 15384 process.cpp:3569] Handling HTTP event for process 'slave(755)' with path: '/slave(755)/api/v1/resource_provider'
I0927 00:47:50.589043 15380 http.cpp:1177] HTTP POST for /slave(755)/api/v1/resource_provider from 172.16.10.152:39882
I0927 00:47:50.589162 15380 manager.cpp:736] Subscribing resource provider {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/home/ubuntu/workspace/mesos/Mesos_CI-build/FLAG/SSL/label/mesos-ec2-ubuntu-14.04/mesos/build/src/test-csi-plugin","--available_capacity=4GB","--volumes=","--work_dir=/tmp/MzmYEU/test_csi_plugin"],"shell":false,"value":"/home/ubuntu/workspace/mesos/Mesos_CI-build/FLAG/SSL/label/mesos-ec2-ubuntu-14.04/mesos/build/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"test_csi_plugin","type":"org.apache.mesos.csi.test"}},"type":"org.apache.mesos.rp.local.storage"}
I0927 00:47:50.592054 15383 slave.cpp:7643] Handling resource provider message 'SUBSCRIBE: {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"9f32619b-a319-4534-9e62-6b2af5da4d0e"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/home/ubuntu/workspace/mesos/Mesos_CI-build/FLAG/SSL/label/mesos-ec2-ubuntu-14.04/mesos/build/src/test-csi-plugin","--available_capacity=4GB","--volumes=","--work_dir=/tmp/MzmYEU/test_csi_plugin"],"shell":false,"value":"/home/ubuntu/workspace/mesos/Mesos_CI-build/FLAG/SSL/label/mesos-ec2-ubuntu-14.04/mesos/build/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"test_csi_plugin","type":"org.apache.mesos.csi.test"}},"type":"org.apache.mesos.rp.local.storage"}'
I0927 00:47:50.592452 15379 provider.cpp:546] Received SUBSCRIBED event
I0927 00:47:50.592473 15379 provider.cpp:1496] Subscribed with ID 9f32619b-a319-4534-9e62-6b2af5da4d0e
I0927 00:47:50.592726 15379 status_update_manager_process.hpp:314] Recovering operation status update manager
I0927 00:47:50.593050 18982 test_csi_plugin.cpp:531] ListVolumesRequest '{}'
I0927 00:47:50.593325 15381 provider.cpp:3519] Sending UPDATE_STATE call with resources '{}' and 0 operations to agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0
I0927 00:47:50.593375 15381 provider.cpp:1110] Resource provider 9f32619b-a319-4534-9e62-6b2af5da4d0e is in READY state
I0927 00:47:50.593475 15381 http_connection.hpp:131] Sending 3 call to http://172.16.10.152:43224/slave(755)/api/v1/resource_provider
I0927 00:47:50.593596 15381 provider.cpp:1422] Updating profiles { test } for resource provider 9f32619b-a319-4534-9e62-6b2af5da4d0e
I0927 00:47:50.593494 15380 status_update_manager_process.hpp:385] Resuming operation status update manager
I0927 00:47:50.594033 15384 process.cpp:3569] Handling HTTP event for process 'slave(755)' with path: '/slave(755)/api/v1/resource_provider'
I0927 00:47:50.594163 18984 test_csi_plugin.cpp:559] GetCapacityRequest '{"volumeCapabilities":[{"mount":{},"accessMode":{"mode":"SINGLE_NODE_WRITER"}}]}'
I0927 00:47:50.594357 15384 http.cpp:1177] HTTP POST for /slave(755)/api/v1/resource_provider from 172.16.10.152:39881
I0927 00:47:50.594449 15384 manager.cpp:917] Received UPDATE_STATE call with resources '[]' and 0 operations from resource provider 9f32619b-a319-4534-9e62-6b2af5da4d0e
I0927 00:47:50.594539 15384 slave.cpp:7643] Handling resource provider message 'UPDATE_STATE: 9f32619b-a319-4534-9e62-6b2af5da4d0e {}'
I0927 00:47:50.594573 15384 slave.cpp:7763] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0927 00:47:50.594897 15383 hierarchical.cpp:740] Grew agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 by {} (total), {  } (used)
I0927 00:47:50.595088 15383 hierarchical.cpp:697] Agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 (ip-172-16-10-152.ec2.internal) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0927 00:47:50.595799 15383 provider.cpp:1297] Removing '{}' and adding 'disk(reservations: [(DYNAMIC,storage)])[RAW(,test)]:4096' to the total resources
I0927 00:47:50.596074 15383 provider.cpp:3519] Sending UPDATE_STATE call with resources 'disk(reservations: [(DYNAMIC,storage)])[RAW(,test)]:4096' and 0 operations to agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0
I0927 00:47:50.596226 15384 http_connection.hpp:131] Sending 3 call to http://172.16.10.152:43224/slave(755)/api/v1/resource_provider
I0927 00:47:50.596462 15380 process.cpp:3569] Handling HTTP event for process 'slave(755)' with path: '/slave(755)/api/v1/resource_provider'
I0927 00:47:50.600713 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:50.600750 15382 hierarchical.cpp:1564] Performed allocation for 1 agents in 88070ns
I0927 00:47:50.634533 15379 http.cpp:1177] HTTP POST for /slave(755)/api/v1/resource_provider from 172.16.10.152:39881
I0927 00:47:50.634691 15379 manager.cpp:917] Received UPDATE_STATE call with resources '[{"disk":{"source":{"profile":"test","type":"RAW"}},"name":"disk","provider_id":{"value":"9f32619b-a319-4534-9e62-6b2af5da4d0e"},"reservations":[{"role":"storage","type":"DYNAMIC"}],"scalar":{"value":4096.0},"type":"SCALAR"}]' and 0 operations from resource provider 9f32619b-a319-4534-9e62-6b2af5da4d0e
I0927 00:47:50.634877 15381 slave.cpp:7643] Handling resource provider message 'UPDATE_STATE: 9f32619b-a319-4534-9e62-6b2af5da4d0e disk(reservations: [(DYNAMIC,storage)])[RAW(,test)]:4096'
I0927 00:47:50.634937 15381 slave.cpp:7763] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage)])[RAW(,test)]:4096
I0927 00:47:50.635401 15378 hierarchical.cpp:697] Agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 (ip-172-16-10-152.ec2.internal) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage)])[RAW(,test)]:4096
I0927 00:47:50.635610 15378 hierarchical.cpp:1564] Performed allocation for 1 agents in 140471ns
I0927 00:47:50.635772 15379 master.cpp:9468] Sending offers [ da47bd11-4a8b-4460-9c85-28d44dfec556-O1 ] to framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 (default) at scheduler-e1c32440-da1c-4f02-b51c-5f6bbf19cc03@172.16.10.152:43224
I0927 00:47:50.635993 15379 sched.cpp:914] Scheduler::resourceOffers took 51344ns
I0927 00:47:50.636377 15383 master.cpp:11462] Removing offer da47bd11-4a8b-4460-9c85-28d44dfec556-O1
I0927 00:47:50.636468 15383 master.cpp:4467] Processing ACCEPT call for offers: [ da47bd11-4a8b-4460-9c85-28d44dfec556-O1 ] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 at slave(755)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal) for framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 (default) at scheduler-e1c32440-da1c-4f02-b51c-5f6bbf19cc03@172.16.10.152:43224
I0927 00:47:50.636512 15383 master.cpp:3896] Authorizing principal 'test-principal' to create a MOUNT disk from 'disk(allocated: storage)(reservations: [(DYNAMIC,storage)])[RAW(,test)]:4096'
I0927 00:47:50.636694 15378 master.cpp:5708] Processing CREATE_DISK operation with source disk(allocated: storage)(reservations: [(DYNAMIC,storage)])[RAW(,test)]:4096 from framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 (default) at scheduler-e1c32440-da1c-4f02-b51c-5f6bbf19cc03@172.16.10.152:43224 to agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 at slave(755)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal)
I0927 00:47:50.636801 15378 master.cpp:11353] Sending operation '' (uuid: eab12426-03f6-4e4a-af35-caa42761a93a) to agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 at slave(755)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal)
I0927 00:47:50.636966 15378 hierarchical.cpp:1236] Recovered cpus(allocated: storage):2; mem(allocated: storage):1024; disk(allocated: storage):1024; ports(allocated: storage):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage)])[RAW(,test)]:4096, allocated: disk(allocated: storage)(reservations: [(DYNAMIC,storage)])[RAW(,test)]:4096) on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 from framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:50.637540 15384 provider.cpp:546] Received APPLY_OPERATION event
I0927 00:47:50.637562 15384 provider.cpp:1536] Received CREATE_DISK operation '' (uuid: eab12426-03f6-4e4a-af35-caa42761a93a)
I0927 00:47:50.638352 18984 test_csi_plugin.cpp:313] CreateVolumeRequest '{"name":"eab12426-03f6-4e4a-af35-caa42761a93a","capacityRange":{"requiredBytes":"4294967296","limitBytes":"4294967296"},"volumeCapabilities":[{"mount":{},"accessMode":{"mode":"SINGLE_NODE_WRITER"}}]}'
I0927 00:47:50.639083 15380 provider.cpp:3038] Applying conversion from 'disk(allocated: storage)(reservations: [(DYNAMIC,storage)])[RAW(,test)]:4096' to 'disk(allocated: storage)(reservations: [(DYNAMIC,storage)])[MOUNT(eab12426-03f6-4e4a-af35-caa42761a93a,test)]:4096' for operation (uuid: eab12426-03f6-4e4a-af35-caa42761a93a)
I0927 00:47:50.639467 15379 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 53489eac-da76-4f2c-be14-3d07c5f01a03) for operation UUID eab12426-03f6-4e4a-af35-caa42761a93a of framework 'da47bd11-4a8b-4460-9c85-28d44dfec556-0000' on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0
I0927 00:47:50.639505 15379 status_update_manager_process.hpp:414] Creating operation status update stream eab12426-03f6-4e4a-af35-caa42761a93a checkpoint=true
I0927 00:47:50.639638 15379 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 53489eac-da76-4f2c-be14-3d07c5f01a03) for operation UUID eab12426-03f6-4e4a-af35-caa42761a93a of framework 'da47bd11-4a8b-4460-9c85-28d44dfec556-0000' on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0
I0927 00:47:50.643877 15379 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 53489eac-da76-4f2c-be14-3d07c5f01a03) for operation UUID eab12426-03f6-4e4a-af35-caa42761a93a of framework 'da47bd11-4a8b-4460-9c85-28d44dfec556-0000' on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0
I0927 00:47:50.644109 15384 http_connection.hpp:131] Sending 2 call to http://172.16.10.152:43224/slave(755)/api/v1/resource_provider
I0927 00:47:50.644397 15377 process.cpp:3569] Handling HTTP event for process 'slave(755)' with path: '/slave(755)/api/v1/resource_provider'
I0927 00:47:50.651526 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:50.651566 15381 hierarchical.cpp:1564] Performed allocation for 1 agents in 84067ns
I0927 00:47:50.682427 15378 http.cpp:1177] HTTP POST for /slave(755)/api/v1/resource_provider from 172.16.10.152:39881
I0927 00:47:50.682750 15381 slave.cpp:7643] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: eab12426-03f6-4e4a-af35-caa42761a93a) for framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0927 00:47:50.683091 15381 slave.cpp:7990] Updating the state of operation with no ID (uuid: eab12426-03f6-4e4a-af35-caa42761a93a) for framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0927 00:47:50.683219 15381 slave.cpp:7828] Forwarding status update of operation with no ID (operation_uuid: eab12426-03f6-4e4a-af35-caa42761a93a) for framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:50.683357 15381 master.cpp:11103] Updating the state of operation '' (uuid: eab12426-03f6-4e4a-af35-caa42761a93a) for framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0927 00:47:50.683571 15382 hierarchical.cpp:967] Updated allocation of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 from disk(allocated: storage)(reservations: [(DYNAMIC,storage)])[RAW(,test)]:4096 to disk(allocated: storage)(reservations: [(DYNAMIC,storage)])[MOUNT(eab12426-03f6-4e4a-af35-caa42761a93a,test)]:4096
I0927 00:47:50.683954 15378 hierarchical.cpp:1236] Recovered disk(allocated: storage)(reservations: [(DYNAMIC,storage)])[MOUNT(eab12426-03f6-4e4a-af35-caa42761a93a,test)]:4096 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage)])[MOUNT(eab12426-03f6-4e4a-af35-caa42761a93a,test)]:4096, allocated: {}) on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 from framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:50.684197 15380 provider.cpp:546] Received ACKNOWLEDGE_OPERATION_STATUS event
I0927 00:47:50.684273 15382 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 53489eac-da76-4f2c-be14-3d07c5f01a03) for stream eab12426-03f6-4e4a-af35-caa42761a93a
I0927 00:47:50.684316 15382 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 53489eac-da76-4f2c-be14-3d07c5f01a03) for operation UUID eab12426-03f6-4e4a-af35-caa42761a93a of framework 'da47bd11-4a8b-4460-9c85-28d44dfec556-0000' on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0
I0927 00:47:50.687628 15382 status_update_manager_process.hpp:490] Cleaning up operation status update stream eab12426-03f6-4e4a-af35-caa42761a93a
I0927 00:47:50.702517 15383 hierarchical.cpp:1564] Performed allocation for 1 agents in 136728ns
I0927 00:47:50.702685 15377 master.cpp:9468] Sending offers [ da47bd11-4a8b-4460-9c85-28d44dfec556-O2 ] to framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 (default) at scheduler-e1c32440-da1c-4f02-b51c-5f6bbf19cc03@172.16.10.152:43224
I0927 00:47:50.702905 15377 sched.cpp:914] Scheduler::resourceOffers took 46862ns
I0927 00:47:50.703152 24729 slave.cpp:909] Agent terminating
I0927 00:47:50.703356 24729 manager.cpp:161] Terminating resource provider 9f32619b-a319-4534-9e62-6b2af5da4d0e
I0927 00:47:50.703598 15379 master.cpp:1251] Agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 at slave(755)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal) disconnected
I0927 00:47:50.703619 15379 master.cpp:3267] Disconnecting agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 at slave(755)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal)
I0927 00:47:50.703634 15379 master.cpp:3286] Deactivating agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 at slave(755)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal)
I0927 00:47:50.703737 15379 master.cpp:11462] Removing offer da47bd11-4a8b-4460-9c85-28d44dfec556-O2
I0927 00:47:50.703781 15379 hierarchical.cpp:795] Agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 deactivated
I0927 00:47:50.703884 15379 hierarchical.cpp:1236] Recovered cpus(allocated: storage):2; mem(allocated: storage):1024; disk(allocated: storage):1024; ports(allocated: storage):[31000-32000]; disk(allocated: storage)(reservations: [(DYNAMIC,storage)])[MOUNT(eab12426-03f6-4e4a-af35-caa42761a93a,test)]:4096 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage)])[MOUNT(eab12426-03f6-4e4a-af35-caa42761a93a,test)]:4096, allocated: {}) on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0 from framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:50.703951 15379 sched.cpp:940] Rescinded offer da47bd11-4a8b-4460-9c85-28d44dfec556-O2
I0927 00:47:50.703969 15379 sched.cpp:951] Scheduler::offerRescinded took 7830ns
W0927 00:47:50.704490 24729 process.cpp:2810] Attempted to spawn already running process files@172.16.10.152:43224
I0927 00:47:50.704908 24729 containerizer.cpp:305] Using isolation { cgroups/cpu, filesystem/posix, environment_secret, cgroups/mem, network/cni }
E0927 00:47:50.706408 15379 http_connection.hpp:452] End-Of-File received
I0927 00:47:50.706462 15379 http_connection.hpp:217] Re-detecting endpoint
I0927 00:47:50.706512 15379 http_connection.hpp:227] New endpoint detected at http://172.16.10.152:43224/slave(755)/api/v1/resource_provider
I0927 00:47:50.706960 15379 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0927 00:47:50.706979 15379 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0927 00:47:50.706991 15379 http_connection.hpp:283] Connected with the remote endpoint at http://172.16.10.152:43224/slave(755)/api/v1/resource_provider
I0927 00:47:50.707126 15379 provider.cpp:536] Disconnected from resource provider manager
I0927 00:47:50.707262 15379 status_update_manager_process.hpp:379] Pausing operation status update manager
I0927 00:47:50.707296 15379 provider.cpp:524] Connected to resource provider manager
I0927 00:47:50.707454 15379 http_connection.hpp:131] Sending 1 call to http://172.16.10.152:43224/slave(755)/api/v1/resource_provider
I0927 00:47:50.707667 15385 process.cpp:2735] Returning '404 Not Found' for '/slave(755)/api/v1/resource_provider'
E0927 00:47:50.707942 15382 provider.cpp:1067] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Received '404 Not Found' ()
I0927 00:47:50.708374 24729 linux_launcher.cpp:144] Using /sys/fs/cgroup/freezer as the freezer hierarchy for the Linux launcher
I0927 00:47:50.708717 24729 provisioner.cpp:298] Using default backend 'aufs'
I0927 00:47:50.714036 24729 cluster.cpp:485] Creating default 'local' authorizer
I0927 00:47:50.714617 15380 slave.cpp:267] Mesos agent started on (756)@172.16.10.152:43224
I0927 00:47:50.714637 15380 slave.cpp:268] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up/store/appc" --authenticate_http_executors="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authentication_timeout_max="1mins" --authentication_timeout_min="5secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_destroy_timeout="1mins" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos_test_cf489fc8-f42c-44dc-8c3a-c44fb4d84a82" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_profile_adaptor="org_apache_mesos_UriDiskProfileAdaptor" --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/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up/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/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --gc_non_executor_container_sandboxes="false" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="cgroups/cpu,cgroups/mem" --jwt_secret_key="/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up/jwt_secret_key" --launcher="linux" --launcher_dir="/home/ubuntu/workspace/mesos/Mesos_CI-build/FLAG/SSL/label/mesos-ec2-ubuntu-14.04/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --memory_profiling="false" --network_cni_metrics="true" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resource_provider_config_dir="/tmp/MzmYEU/resource_provider_configs" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up" --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/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_irDZ49" --zk_session_timeout="10secs"
I0927 00:47:50.714813 15380 credentials.hpp:86] Loading credential for authentication from '/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up/credential'
I0927 00:47:50.714875 15380 slave.cpp:300] Agent using credential for: test-principal
I0927 00:47:50.714960 15380 credentials.hpp:37] Loading credentials for authentication from '/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_rR80up/http_credentials'
I0927 00:47:50.715114 15380 http.cpp:1037] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0927 00:47:50.715169 15380 http.cpp:1058] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0927 00:47:50.715302 15380 http.cpp:1037] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0927 00:47:50.715342 15380 http.cpp:1058] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0927 00:47:50.715381 15380 http.cpp:1037] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0927 00:47:50.715492 15380 http.cpp:1058] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0927 00:47:50.715667 15380 disk_profile_adaptor.cpp:84] Creating disk profile adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I0927 00:47:50.716048 15381 uri_disk_profile_adaptor.cpp:304] Updated disk profile mapping to 1 active profiles
I0927 00:47:50.716500 15380 slave.cpp:615] 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"}]
I0927 00:47:50.716639 15380 slave.cpp:623] Agent attributes: [  ]
I0927 00:47:50.716648 15380 slave.cpp:632] Agent hostname: ip-172-16-10-152.ec2.internal
I0927 00:47:50.716697 15379 task_status_update_manager.cpp:181] Pausing sending task status updates
I0927 00:47:50.717015 15380 state.cpp:66] Recovering state from '/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_irDZ49/meta'
I0927 00:47:50.717051 15380 state.cpp:711] No committed checkpointed resources found at '/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_irDZ49/meta/resources/resources.info'
I0927 00:47:50.717121 15380 state.cpp:110] Failed to find the latest agent from '/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_irDZ49/meta'
I0927 00:47:50.717170 15381 slave.cpp:6909] Finished recovering checkpointed state from '/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_irDZ49/meta', beginning agent recovery
I0927 00:47:50.717221 15381 task_status_update_manager.cpp:207] Recovering task status update manager
I0927 00:47:50.717315 15383 containerizer.cpp:727] Recovering Mesos containers
I0927 00:47:50.717623 15377 linux_launcher.cpp:286] Recovering Linux launcher
I0927 00:47:50.717787 15377 linux_launcher.cpp:343] Recovered container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:47:50.717856 15377 containerizer.cpp:1053] Recovering isolators
I0927 00:47:50.718397 15379 memory.cpp:478] Started listening for OOM events for container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:47:50.718439 15379 memory.cpp:590] Started listening on 'low' memory pressure events for container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:47:50.718458 15379 memory.cpp:590] Started listening on 'medium' memory pressure events for container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:47:50.718477 15379 memory.cpp:590] Started listening on 'critical' memory pressure events for container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:47:50.719173 15384 containerizer.cpp:1092] Recovering provisioner
I0927 00:47:50.719292 15379 provisioner.cpp:494] Provisioner recovery complete
I0927 00:47:50.719662 15380 composing.cpp:339] Finished recovering all containerizers
I0927 00:47:50.719699 15379 slave.cpp:7138] Recovering executors
I0927 00:47:50.719750 15377 slave.cpp:7291] Finished recovery
I0927 00:47:50.722167 15377 slave.cpp:1254] New master detected at master@172.16.10.152:43224
I0927 00:47:50.722173 15382 task_status_update_manager.cpp:181] Pausing sending task status updates
I0927 00:47:50.722208 15377 slave.cpp:1319] Detecting new master
I0927 00:47:50.726370 15380 slave.cpp:1346] Authenticating with master master@172.16.10.152:43224
I0927 00:47:50.726395 15380 slave.cpp:1355] Using default CRAM-MD5 authenticatee
I0927 00:47:50.730116 15382 authenticatee.cpp:121] Creating new client SASL connection
I0927 00:47:50.730759 15382 master.cpp:9653] Authenticating slave(756)@172.16.10.152:43224
I0927 00:47:50.730854 15382 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1467)@172.16.10.152:43224
I0927 00:47:50.730913 15382 authenticator.cpp:98] Creating new server SASL connection
I0927 00:47:50.731395 15382 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0927 00:47:50.731413 15382 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0927 00:47:50.731438 15382 authenticator.cpp:204] Received SASL authentication start
I0927 00:47:50.731472 15382 authenticator.cpp:326] Authentication requires more steps
I0927 00:47:50.731495 15382 authenticatee.cpp:259] Received SASL authentication step
I0927 00:47:50.731523 15382 authenticator.cpp:232] Received SASL authentication step
I0927 00:47:50.731535 15382 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ip-172-16-10-152.ec2.internal' server FQDN: 'ip-172-16-10-152.ec2.internal' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0927 00:47:50.731542 15382 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0927 00:47:50.731554 15382 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0927 00:47:50.731562 15382 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ip-172-16-10-152.ec2.internal' server FQDN: 'ip-172-16-10-152.ec2.internal' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0927 00:47:50.731568 15382 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0927 00:47:50.731575 15382 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0927 00:47:50.731585 15382 authenticator.cpp:318] Authentication success
I0927 00:47:50.731614 15382 authenticatee.cpp:299] Authentication success
I0927 00:47:50.731645 15382 master.cpp:9685] Successfully authenticated principal 'test-principal' at slave(756)@172.16.10.152:43224
I0927 00:47:50.731662 15382 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1467)@172.16.10.152:43224
I0927 00:47:50.737592 15380 slave.cpp:1446] Successfully authenticated with master master@172.16.10.152:43224
I0927 00:47:50.737764 15380 slave.cpp:1877] Will retry registration in 14.529877ms if necessary
I0927 00:47:50.737818 15383 master.cpp:6605] Received register agent message from slave(756)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal)
I0927 00:47:50.737960 15383 master.cpp:3964] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal'
I0927 00:47:50.738137 15379 master.cpp:6672] Authorized registration of agent at slave(756)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal)
I0927 00:47:50.738229 15379 master.cpp:6787] Registering agent at slave(756)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal) with id da47bd11-4a8b-4460-9c85-28d44dfec556-S1
I0927 00:47:50.738430 15379 registrar.cpp:487] Applied 1 operations in 47126ns; attempting to update the registry
I0927 00:47:50.742255 15379 registrar.cpp:544] Successfully updated the registry in 3.732992ms
I0927 00:47:50.742492 15379 master.cpp:6835] Admitted agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 at slave(756)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal)
I0927 00:47:50.742624 15379 master.cpp:6880] Registered agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 at slave(756)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0927 00:47:50.742805 15380 slave.cpp:1479] Registered with master master@172.16.10.152:43224; given agent ID da47bd11-4a8b-4460-9c85-28d44dfec556-S1
I0927 00:47:50.742848 15383 task_status_update_manager.cpp:188] Resuming sending task status updates
I0927 00:47:50.743023 15380 slave.cpp:1499] Checkpointing SlaveInfo to '/tmp/StorageLocalResourceProviderTest_AgentRegisteredWithNewId_irDZ49/meta/slaves/da47bd11-4a8b-4460-9c85-28d44dfec556-S1/slave.info'
I0927 00:47:50.742683 15382 hierarchical.cpp:601] Added agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 (ip-172-16-10-152.ec2.internal) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0927 00:47:50.743602 15383 master.cpp:9468] Sending offers [ da47bd11-4a8b-4460-9c85-28d44dfec556-O3 ] to framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 (default) at scheduler-e1c32440-da1c-4f02-b51c-5f6bbf19cc03@172.16.10.152:43224
I0927 00:47:50.743712 15382 hierarchical.cpp:1564] Performed allocation for 1 agents in 315482ns
I0927 00:47:50.750180 15383 sched.cpp:914] Scheduler::resourceOffers took 34570ns
I0927 00:47:50.750205 15380 slave.cpp:1548] Forwarding agent update {"operations":{},"resource_version_uuid":{"value":"bYiqSEGtTv6c/6obzS5lRw=="},"slave_id":{"value":"da47bd11-4a8b-4460-9c85-28d44dfec556-S1"},"update_oversubscribed_resources":false}
I0927 00:47:50.750614 15377 master.cpp:7939] Ignoring update on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 at slave(756)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal) as it reports no changes
I0927 00:47:50.750725 15377 master.cpp:5850] Processing DECLINE call for offers: [ da47bd11-4a8b-4460-9c85-28d44dfec556-O3 ] for framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 (default) at scheduler-e1c32440-da1c-4f02-b51c-5f6bbf19cc03@172.16.10.152:43224
I0927 00:47:50.750847 15377 master.cpp:11462] Removing offer da47bd11-4a8b-4460-9c85-28d44dfec556-O3
I0927 00:47:50.751217 15383 hierarchical.cpp:1236] Recovered cpus(allocated: storage):2; mem(allocated: storage):1024; disk(allocated: storage):1024; ports(allocated: storage):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 from framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:50.751250 15383 hierarchical.cpp:1282] Framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 filtered agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for 365days
I0927 00:47:50.753219 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:50.753254 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 98221ns
I0927 00:47:50.803673 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:50.803733 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 164667ns
I0927 00:47:50.855062 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:50.855119 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 193931ns
I0927 00:47:50.906332 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:50.906391 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 138817ns
I0927 00:47:50.957666 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:50.957725 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 133956ns
I0927 00:47:51.008142 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.008203 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 166210ns
I0927 00:47:51.059468 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.059527 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 133316ns
I0927 00:47:51.109905 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.109963 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 134482ns
I0927 00:47:51.161239 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.161298 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 133157ns
I0927 00:47:51.211720 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.211779 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 139413ns
I0927 00:47:51.263057 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.263115 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 130520ns
I0927 00:47:51.313499 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.313557 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 135797ns
I0927 00:47:51.364811 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.364868 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 127838ns
I0927 00:47:51.415226 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.415282 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 125162ns
I0927 00:47:51.465682 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.465737 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 121571ns
I0927 00:47:51.516086 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.516144 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 123839ns
I0927 00:47:51.567406 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.567464 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 127956ns
I0927 00:47:51.617884 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.617944 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 133638ns
I0927 00:47:51.669204 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.669265 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 132627ns
I0927 00:47:51.720583 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.720644 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 135648ns
I0927 00:47:51.771915 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.771976 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 132020ns
I0927 00:47:51.822363 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.822424 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 137759ns
I0927 00:47:51.873672 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.873730 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 128910ns
I0927 00:47:51.924121 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.924181 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 132874ns
I0927 00:47:51.975425 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:51.975483 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 127052ns
I0927 00:47:52.025864 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.025924 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 135398ns
I0927 00:47:52.077195 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.077256 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 132729ns
I0927 00:47:52.127640 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.127698 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 135804ns
I0927 00:47:52.178944 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.179005 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 132715ns
I0927 00:47:52.229389 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.229451 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 136587ns
I0927 00:47:52.280741 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.280804 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 134708ns
I0927 00:47:52.331171 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.331230 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 129271ns
I0927 00:47:52.382483 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.382539 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 126134ns
I0927 00:47:52.432901 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.432960 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 131012ns
I0927 00:47:52.484217 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.484278 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 133276ns
I0927 00:47:52.534643 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.534704 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 135033ns
I0927 00:47:52.585963 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.586025 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 133159ns
I0927 00:47:52.636421 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.636483 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 139816ns
I0927 00:47:52.687729 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.687790 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 132397ns
I0927 00:47:52.738165 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.738224 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 132974ns
I0927 00:47:52.789489 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.789549 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 132126ns
I0927 00:47:52.840832 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.840893 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 133202ns
I0927 00:47:52.892144 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.892204 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 133209ns
I0927 00:47:52.942571 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.942628 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 128568ns
I0927 00:47:52.993046 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:52.993105 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 130541ns
I0927 00:47:53.043794 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:53.043856 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 133798ns
I0927 00:47:53.095101 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:53.095158 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 125960ns
I0927 00:47:53.145536 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:53.145596 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 133459ns
I0927 00:47:53.196857 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:53.196916 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 129148ns
I0927 00:47:53.248272 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:53.248334 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 189884ns
I0927 00:47:53.298684 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:53.298746 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 130872ns
I0927 00:47:53.349270 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:53.349330 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 129990ns
I0927 00:47:53.400594 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:53.400653 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 128279ns
I0927 00:47:53.451058 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:53.451117 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 127505ns
I0927 00:47:53.501914 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:53.501978 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 133469ns
I0927 00:47:53.553259 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:53.553319 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 132068ns
I0927 00:47:53.604580 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:53.604641 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 130395ns
I0927 00:47:53.655050 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:53.655110 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 132313ns
I0927 00:47:53.705515 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:53.705574 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 129333ns
I0927 00:47:53.755949 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:53.756006 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 131464ns
I0927 00:47:53.806401 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:53.806460 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 134572ns
I0927 00:47:53.856863 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:53.856921 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 129550ns
I0927 00:47:53.907312 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:53.907371 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 130434ns
I0927 00:47:53.957813 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:53.957875 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 132898ns
I0927 00:47:54.008275 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.008337 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 132313ns
I0927 00:47:54.059628 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.059687 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 131263ns
I0927 00:47:54.110939 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.110998 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 131633ns
I0927 00:47:54.161381 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.161438 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 132035ns
I0927 00:47:54.211864 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.211926 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 132873ns
I0927 00:47:54.262310 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.262373 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 137804ns
I0927 00:47:54.313632 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.313693 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 132317ns
I0927 00:47:54.364063 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.364123 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 132397ns
I0927 00:47:54.415383 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.415444 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 130949ns
I0927 00:47:54.466704 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.466765 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 132714ns
I0927 00:47:54.517134 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.517195 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 131474ns
I0927 00:47:54.568435 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.568492 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 124330ns
I0927 00:47:54.618863 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.618922 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 133390ns
I0927 00:47:54.670172 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.670230 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 133527ns
I0927 00:47:54.720602 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.720662 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 133144ns
I0927 00:47:54.771908 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.771968 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 128206ns
I0927 00:47:54.822342 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.822405 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 135246ns
I0927 00:47:54.873643 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.873700 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 127358ns
I0927 00:47:54.924067 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.924125 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 129165ns
I0927 00:47:54.975373 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:54.975432 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 129559ns
I0927 00:47:55.025799 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.025861 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 132171ns
I0927 00:47:55.077168 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.077229 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 135033ns
I0927 00:47:55.127606 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.127667 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 129331ns
I0927 00:47:55.178922 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.178982 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 133660ns
I0927 00:47:55.229379 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.229439 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 132963ns
I0927 00:47:55.280685 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.280746 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 132318ns
I0927 00:47:55.331115 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.331173 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 127764ns
I0927 00:47:55.382431 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.382488 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 128024ns
I0927 00:47:55.432868 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.432926 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 132311ns
I0927 00:47:55.484180 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.484239 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 128605ns
I0927 00:47:55.534612 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.534672 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 132406ns
I0927 00:47:55.585928 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.585988 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 130838ns
I0927 00:47:55.636382 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.636442 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 135339ns
I0927 00:47:55.687706 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.687767 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 134624ns
I0927 00:47:55.738212 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.738272 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 131995ns
I0927 00:47:55.789541 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.789599 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 133346ns
I0927 00:47:55.839993 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.840054 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 133442ns
I0927 00:47:55.891294 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.891350 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 129715ns
I0927 00:47:55.941723 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.941782 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 129915ns
I0927 00:47:55.993027 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:55.993083 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 124623ns
I0927 00:47:56.043444 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:56.043506 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 132010ns
I0927 00:47:56.094763 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:56.094822 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 128929ns
I0927 00:47:56.145208 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:56.145270 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 133106ns
I0927 00:47:56.196514 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:56.196573 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 128061ns
I0927 00:47:56.246966 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:56.247027 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 132913ns
I0927 00:47:56.298288 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:56.298348 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 132425ns
I0927 00:47:56.348716 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:56.348776 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 131687ns
I0927 00:47:56.400032 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:56.400089 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 128789ns
I0927 00:47:56.450541 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:56.450598 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 127184ns
I0927 00:47:56.501878 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:56.501937 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 132820ns
I0927 00:47:56.552414 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:56.552474 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 132974ns
I0927 00:47:56.603735 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:56.603796 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 133108ns
I0927 00:47:56.654278 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:56.654340 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 135650ns
I0927 00:47:56.705584 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:56.705643 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 132418ns
I0927 00:47:56.756099 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:56.756160 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 131473ns
I0927 00:47:56.807417 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:56.807477 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 129241ns
I0927 00:47:56.857950 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:56.858011 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 130430ns
I0927 00:47:56.909271 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:56.909332 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 130746ns
I0927 00:47:56.959812 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:56.959873 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 131419ns
I0927 00:47:57.011144 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.011204 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 129223ns
I0927 00:47:57.061656 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.061715 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 130142ns
I0927 00:47:57.112253 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.112313 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 131030ns
I0927 00:47:57.162770 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.162830 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 133284ns
I0927 00:47:57.214097 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.214174 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 148419ns
I0927 00:47:57.264652 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.264714 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 136318ns
I0927 00:47:57.315999 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.316056 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 130327ns
I0927 00:47:57.366502 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.366561 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 130158ns
I0927 00:47:57.417827 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.417886 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 130240ns
I0927 00:47:57.468329 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.468390 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 130754ns
I0927 00:47:57.518910 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.518967 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 129094ns
I0927 00:47:57.569391 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.569447 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 121583ns
I0927 00:47:57.620669 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.620718 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 105276ns
I0927 00:47:57.671082 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.671130 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 105472ns
I0927 00:47:57.722420 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.722477 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 127932ns
I0927 00:47:57.772919 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.772979 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 131728ns
I0927 00:47:57.824260 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.824321 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 133417ns
I0927 00:47:57.874671 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.874730 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 127120ns
I0927 00:47:57.925985 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.926045 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 129093ns
I0927 00:47:57.976541 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:57.976600 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 133038ns
I0927 00:47:58.027880 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.027943 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 134622ns
I0927 00:47:58.078305 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.078366 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 131037ns
I0927 00:47:58.129650 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.129709 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 130733ns
I0927 00:47:58.180166 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.180227 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 136110ns
I0927 00:47:58.230756 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.230814 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 133720ns
I0927 00:47:58.281260 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.281321 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 131794ns
I0927 00:47:58.332588 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.332646 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 128647ns
I0927 00:47:58.383075 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.383134 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 126671ns
I0927 00:47:58.434415 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.434476 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 132169ns
I0927 00:47:58.484938 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.484999 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 131362ns
I0927 00:47:58.536273 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.536334 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 133960ns
I0927 00:47:58.586712 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.586776 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 133463ns
I0927 00:47:58.638041 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.638119 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 152915ns
I0927 00:47:58.688588 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.688650 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 134143ns
I0927 00:47:58.739949 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.740010 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 134024ns
I0927 00:47:58.790400 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.790459 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 133017ns
I0927 00:47:58.841738 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.841797 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 132952ns
I0927 00:47:58.892271 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.892330 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 132255ns
I0927 00:47:58.943599 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.943656 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 130390ns
I0927 00:47:58.994083 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:58.994159 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 144966ns
I0927 00:47:59.045439 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:59.045501 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 135141ns
I0927 00:47:59.095954 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:59.096019 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 132662ns
I0927 00:47:59.147271 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:59.147330 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 131904ns
I0927 00:47:59.197690 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:59.197747 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 124963ns
I0927 00:47:59.248332 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:59.248394 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 135544ns
I0927 00:47:59.299664 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:59.299723 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 130428ns
I0927 00:47:59.351032 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:59.351097 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 137549ns
I0927 00:47:59.402350 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:59.402415 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 133513ns
I0927 00:47:59.453683 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:59.453742 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 131193ns
I0927 00:47:59.504178 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:59.504238 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 132634ns
I0927 00:47:59.555433 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:59.555492 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 128269ns
I0927 00:47:59.605870 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:59.605931 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 130535ns
I0927 00:47:59.657214 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:59.657275 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 135702ns
I0927 00:47:59.708514 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:59.708573 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 127971ns
I0927 00:47:59.759030 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:59.759090 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 134685ns
I0927 00:47:59.810344 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:59.810405 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 130231ns
I0927 00:47:59.860873 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:59.860934 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 132270ns
I0927 00:47:59.912171 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:59.912231 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 127669ns
I0927 00:47:59.962750 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:47:59.962810 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 191241ns
I0927 00:48:00.014006 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.014084 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 148074ns
I0927 00:48:00.064537 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.064597 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 132982ns
I0927 00:48:00.115018 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.115079 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 133175ns
I0927 00:48:00.165498 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.165560 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 135944ns
I0927 00:48:00.216802 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.216861 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 131546ns
I0927 00:48:00.267256 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.267320 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 141040ns
I0927 00:48:00.318575 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.318636 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 129565ns
I0927 00:48:00.369084 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.369145 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 130425ns
I0927 00:48:00.420404 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.420465 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 130916ns
I0927 00:48:00.470844 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.470903 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 130929ns
I0927 00:48:00.522176 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.522236 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 131182ns
I0927 00:48:00.572612 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.572669 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 131820ns
I0927 00:48:00.623935 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.623997 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 131023ns
I0927 00:48:00.675284 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.675344 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 134469ns
I0927 00:48:00.726604 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.726663 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 129497ns
I0927 00:48:00.777084 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.777140 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 121244ns
I0927 00:48:00.828418 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.828477 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 132154ns
I0927 00:48:00.878945 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.879007 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 133483ns
I0927 00:48:00.930249 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.930310 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 129648ns
I0927 00:48:00.980674 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:00.980731 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 126967ns
I0927 00:48:01.032001 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.032061 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 131691ns
I0927 00:48:01.082434 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.082494 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 135520ns
I0927 00:48:01.133751 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.133812 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 129876ns
I0927 00:48:01.184265 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.184324 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 130943ns
I0927 00:48:01.235589 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.235648 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 132516ns
I0927 00:48:01.286103 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.286180 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 147808ns
I0927 00:48:01.337431 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.337491 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 130985ns
I0927 00:48:01.387945 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.388005 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 131908ns
I0927 00:48:01.438598 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.438660 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 133668ns
I0927 00:48:01.489042 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.489104 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 135502ns
I0927 00:48:01.540388 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.540452 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 136060ns
I0927 00:48:01.590839 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.590903 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 139449ns
I0927 00:48:01.641513 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.641574 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 137636ns
I0927 00:48:01.692034 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.692096 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 136870ns
I0927 00:48:01.743407 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.743468 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 133521ns
I0927 00:48:01.793859 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.793918 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 132801ns
I0927 00:48:01.845232 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.845295 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 136675ns
I0927 00:48:01.895676 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.895738 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 134894ns
I0927 00:48:01.947019 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.947082 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 131998ns
I0927 00:48:01.997546 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:01.997608 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 136173ns
I0927 00:48:02.048100 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:02.048161 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 133977ns
I0927 00:48:02.098645 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:02.098706 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 131838ns
I0927 00:48:02.150054 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:02.150133 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 152893ns
I0927 00:48:02.200526 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:02.200589 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 134670ns
I0927 00:48:02.251240 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:02.251303 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 137075ns
I0927 00:48:02.301791 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:02.301853 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 134083ns
I0927 00:48:02.353166 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:02.353227 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 132033ns
I0927 00:48:02.403683 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:02.403745 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 137689ns
I0927 00:48:02.455070 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:02.455130 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 131041ns
I0927 00:48:02.505539 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:02.505601 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 134686ns
I0927 00:48:02.556924 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:02.556988 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 136427ns
I0927 00:48:02.607477 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:02.607539 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 133757ns
I0927 00:48:02.658893 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:02.658957 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 140007ns
I0927 00:48:02.709439 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:02.709501 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 136579ns
I0927 00:48:02.760803 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:02.760862 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 133016ns
I0927 00:48:02.811249 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:02.811311 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 133113ns
I0927 00:48:02.862646 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:02.862709 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 135605ns
I0927 00:48:02.913198 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:02.913261 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 135804ns
I0927 00:48:02.964558 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:02.964619 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 133017ns
I0927 00:48:03.015099 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.015161 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 133892ns
I0927 00:48:03.066480 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.066541 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 132915ns
I0927 00:48:03.116927 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.116989 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 134220ns
I0927 00:48:03.168318 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.168380 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 135108ns
I0927 00:48:03.218796 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.218858 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 135533ns
I0927 00:48:03.270182 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.270242 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 134707ns
I0927 00:48:03.320652 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.320713 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 132673ns
I0927 00:48:03.372012 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.372073 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 134476ns
I0927 00:48:03.422472 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.422534 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 136181ns
I0927 00:48:03.473806 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.473868 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 133957ns
I0927 00:48:03.524348 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.524408 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 132333ns
I0927 00:48:03.575704 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.575764 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 131272ns
I0927 00:48:03.627089 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.627152 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 136731ns
I0927 00:48:03.677605 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.677670 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 142344ns
I0927 00:48:03.728078 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.728142 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 138540ns
I0927 00:48:03.779436 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.779500 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 134507ns
I0927 00:48:03.829877 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.829937 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 134183ns
I0927 00:48:03.881242 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.881304 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 131111ns
I0927 00:48:03.931711 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.931773 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 134360ns
I0927 00:48:03.983078 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:03.983142 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 133304ns
I0927 00:48:04.033560 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:04.033622 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 139435ns
I0927 00:48:04.084908 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:04.084969 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 135356ns
I0927 00:48:04.135368 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:04.135429 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 134607ns
I0927 00:48:04.186704 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:04.186766 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 137671ns
I0927 00:48:04.237174 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:04.237237 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 137273ns
I0927 00:48:04.288516 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:04.288578 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 139170ns
I0927 00:48:04.339098 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:04.339160 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 166168ns
I0927 00:48:04.390417 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:04.390480 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 132037ns
I0927 00:48:04.440862 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:04.440924 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 137377ns
I0927 00:48:04.492197 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:04.492257 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 132783ns
I0927 00:48:04.543552 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:04.543614 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 134399ns
I0927 00:48:04.594866 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:04.594929 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 133914ns
I0927 00:48:04.645390 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:04.645450 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 136551ns
I0927 00:48:04.696734 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:04.696796 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 134345ns
I0927 00:48:04.747539 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:04.747601 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 135277ns
I0927 00:48:04.798864 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:04.798924 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 131894ns
I0927 00:48:04.849392 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:04.849453 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 134752ns
I0927 00:48:04.900710 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:04.900774 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 131295ns
I0927 00:48:04.951378 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:04.951438 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 132218ns
I0927 00:48:05.002705 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:05.002768 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 136947ns
I0927 00:48:05.054029 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:05.054109 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 153651ns
I0927 00:48:05.104516 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:05.104578 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 130710ns
I0927 00:48:05.155848 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:05.155908 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 137856ns
I0927 00:48:05.207242 15380 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:05.207305 15380 hierarchical.cpp:1564] Performed allocation for 2 agents in 134750ns
I0927 00:48:05.258570 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:05.258631 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 135056ns
I0927 00:48:05.309115 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:05.309176 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 133066ns
I0927 00:48:05.360450 15378 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:05.360513 15378 hierarchical.cpp:1564] Performed allocation for 2 agents in 134795ns
I0927 00:48:05.411849 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:05.411911 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 137495ns
I0927 00:48:05.462769 15381 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:05.462832 15381 hierarchical.cpp:1564] Performed allocation for 2 agents in 134539ns
I0927 00:48:05.513309 15384 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:05.513368 15384 hierarchical.cpp:1564] Performed allocation for 2 agents in 132650ns
I0927 00:48:05.564666 15379 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:05.564729 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 134567ns
I0927 00:48:05.615110 15377 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:05.615172 15377 hierarchical.cpp:1564] Performed allocation for 2 agents in 134772ns
I0927 00:48:05.665634 15383 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:05.665697 15383 hierarchical.cpp:1564] Performed allocation for 2 agents in 136830ns
I0927 00:48:05.716115 15382 hierarchical.cpp:2386] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 for role storage of framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:05.716228 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 184215ns
../../src/tests/storage_local_resource_provider_tests.cpp:1588: Failure
Failed to wait 15secs for slaveRecoveredOffers
../../src/tests/storage_local_resource_provider_tests.cpp:1579: Failure
Actual function call count doesn't match EXPECT_CALL(sched, resourceOffers(&driver, OffersHaveAnyResource( isPreExistingVolume)))...
         Expected: to be called once
I           Actual: never called - unsatisfied and active
0927 00:48:05.759968 15378 master.cpp:1366] Framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 (default) at scheduler-e1c32440-da1c-4f02-b51c-5f6bbf19cc03@172.16.10.152:43224 disconnected
I0927 00:48:05.760025 15378 master.cpp:3230] Deactivating framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 (default) at scheduler-e1c32440-da1c-4f02-b51c-5f6bbf19cc03@172.16.10.152:43224
I0927 00:48:05.760047 15378 master.cpp:3207] Disconnecting framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 (default) at scheduler-e1c32440-da1c-4f02-b51c-5f6bbf19cc03@172.16.10.152:43224
I0927 00:48:05.760059 15378 master.cpp:1381] Giving framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 (default) at scheduler-e1c32440-da1c-4f02-b51c-5f6bbf19cc03@172.16.10.152:43224 0ns to failover
I0927 00:48:05.760324 15377 hierarchical.cpp:420] Deactivated framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:05.760397 15381 master.cpp:9261] Framework failover timeout, removing framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 (default) at scheduler-e1c32440-da1c-4f02-b51c-5f6bbf19cc03@172.16.10.152:43224
I0927 00:48:05.760411 15381 master.cpp:10197] Removing framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000 (default) at scheduler-e1c32440-da1c-4f02-b51c-5f6bbf19cc03@172.16.10.152:43224
I0927 00:48:05.760454 24729 slave.cpp:909] Agent terminating
I0927 00:48:05.760715 15378 master.cpp:1251] Agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 at slave(756)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal) disconnected
I0927 00:48:05.760735 15378 master.cpp:3267] Disconnecting agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 at slave(756)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal)
I0927 00:48:05.760748 15378 master.cpp:3286] Deactivating agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 at slave(756)@172.16.10.152:43224 (ip-172-16-10-152.ec2.internal)
I0927 00:48:05.760521 15381 hierarchical.cpp:359] Removed framework da47bd11-4a8b-4460-9c85-28d44dfec556-0000
I0927 00:48:05.760861 15381 hierarchical.cpp:795] Agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1 deactivated
I0927 00:48:05.760866 15380 containerizer.cpp:2455] Destroying container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE in RUNNING state
I0927 00:48:05.760879 15380 containerizer.cpp:3118] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE from RUNNING to DESTROYING
I0927 00:48:05.760942 15380 linux_launcher.cpp:580] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:48:05.760987 15380 linux_launcher.cpp:622] Destroying cgroup '/sys/fs/cgroup/freezer/mesos_test_cf489fc8-f42c-44dc-8c3a-c44fb4d84a82/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE'
I0927 00:48:05.761175 15377 cgroups.cpp:2838] Freezing cgroup /sys/fs/cgroup/freezer/mesos_test_cf489fc8-f42c-44dc-8c3a-c44fb4d84a82/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:48:05.761394 15377 cgroups.cpp:1229] Successfully froze cgroup /sys/fs/cgroup/freezer/mesos_test_cf489fc8-f42c-44dc-8c3a-c44fb4d84a82/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE after 190976ns
I0927 00:48:05.761597 15377 cgroups.cpp:2856] Thawing cgroup /sys/fs/cgroup/freezer/mesos_test_cf489fc8-f42c-44dc-8c3a-c44fb4d84a82/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:48:05.762966 15377 cgroups.cpp:1258] Successfully thawed cgroup /sys/fs/cgroup/freezer/mesos_test_cf489fc8-f42c-44dc-8c3a-c44fb4d84a82/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE after 1.336832ms
I0927 00:48:05.766497 15382 hierarchical.cpp:1564] Performed allocation for 2 agents in 24769ns
I0927 00:48:05.816908 15379 hierarchical.cpp:1564] Performed allocation for 2 agents in 28310ns
I0927 00:48:05.858539 15378 containerizer.cpp:2957] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE has exited
I0927 00:48:05.859663 15383 provisioner.cpp:597] Ignoring destroy request for unknown container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin--CONTROLLER_SERVICE-NODE_SERVICE
I0927 00:48:05.862931 24729 master.cpp:1093] Master terminating
I0927 00:48:05.863080 15381 hierarchical.cpp:637] Removed agent da47bd11-4a8b-4460-9c85-28d44dfec556-S1
I0927 00:48:05.863313 15382 hierarchical.cpp:637] Removed agent da47bd11-4a8b-4460-9c85-28d44dfec556-S0
I0927 00:48:05.868171 15378 cgroups.cpp:2838] Freezing cgroup /sys/fs/cgroup/freezer/mesos_test_cf489fc8-f42c-44dc-8c3a-c44fb4d84a82
I0927 00:48:05.868314 15378 cgroups.cpp:1229] Successfully froze cgroup /sys/fs/cgroup/freezer/mesos_test_cf489fc8-f42c-44dc-8c3a-c44fb4d84a82 after 112896ns
I0927 00:48:05.868456 15378 cgroups.cpp:2856] Thawing cgroup /sys/fs/cgroup/freezer/mesos_test_cf489fc8-f42c-44dc-8c3a-c44fb4d84a82
I0927 00:48:05.868540 15378 cgroups.cpp:1258] Successfully thawed cgroup /sys/fs/cgroup/freezer/mesos_test_cf489fc8-f42c-44dc-8c3a-c44fb4d84a82 after 56064ns
[  FAILED  ] StorageLocalResourceProviderTest.AgentRegisteredWithNewId (15493 ms)
{noformat}

> StorageLocalResourceProviderTest.AgentRegisteredWithNewId is flaky
> ------------------------------------------------------------------
>
>                 Key: MESOS-9244
>                 URL: https://issues.apache.org/jira/browse/MESOS-9244
>             Project: Mesos
>          Issue Type: Bug
>          Components: storage
>            Reporter: Benjamin Bannier
>            Priority: Major
>              Labels: flaky, flaky-test, storage
>         Attachments: consoleText.txt
>
>
> We saw {{StorageLocalResourceProviderTest.AgentRegisteredWithNewId}} fail in our CI with
> {noformat}
> ../../src/tests/storage_local_resource_provider_tests.cpp:1604
> Failed to wait 15secs for slaveRecoveredOffers
> {noformat}



--
This message was sent by Atlassian JIRA
(v7.6.3#76005)

Mime
View raw message