See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4491/display/redirect?page=changes>
Changes: [alexr] Added a comment about PythonFramework test system requirements. ------------------------------------------ [...truncated 31.13 MB...] 3: I1122 17:09:04.829949 17385 hierarchical.cpp:1457] Performed allocation for 1 agents in 293625ns 3: I1122 17:09:04.844635 17378 slave.cpp:5135] Got exited event for executor(1)@172.17.0.2:35115 3: I1122 17:09:04.921044 17386 containerizer.cpp:2773] Container e63216da-df56-4a6c-9849-80ec65571b0f has exited 3: I1122 17:09:04.923440 17377 provisioner.cpp:557] Ignoring destroy request for unknown container e63216da-df56-4a6c-9849-80ec65571b0f 3: I1122 17:09:04.924378 17375 slave.cpp:5544] Executor 'e57b35e8-1889-450e-b0d0-d4386a38347a' of framework 77e050e7-7b30-41f0-88c5-a808d053f45c-0001 terminated with signal Killed 3: I1122 17:09:04.924437 17375 slave.cpp:5648] Cleaning up executor 'e57b35e8-1889-450e-b0d0-d4386a38347a' of framework 77e050e7-7b30-41f0-88c5-a808d053f45c-0001 at executor(1)@172.17.0.2:35115 3: I1122 17:09:04.924705 17379 gc.cpp:90] Scheduling '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMultipleFrameworks_1_FjwzLB/slaves/77e050e7-7b30-41f0-88c5-a808d053f45c-S0/frameworks/77e050e7-7b30-41f0-88c5-a808d053f45c-0001/executors/e57b35e8-1889-450e-b0d0-d4386a38347a/runs/e63216da-df56-4a6c-9849-80ec65571b0f' for gc 6.99998929853926days in the future 3: I1122 17:09:04.924870 17375 slave.cpp:5755] Cleaning up framework 77e050e7-7b30-41f0-88c5-a808d053f45c-0001 3: I1122 17:09:04.924916 17379 gc.cpp:90] Scheduling '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMultipleFrameworks_1_FjwzLB/slaves/77e050e7-7b30-41f0-88c5-a808d053f45c-S0/frameworks/77e050e7-7b30-41f0-88c5-a808d053f45c-0001/executors/e57b35e8-1889-450e-b0d0-d4386a38347a' for gc 6.9999892964days in the future 3: I1122 17:09:04.924969 17382 task_status_update_manager.cpp:289] Closing task status update streams for framework 77e050e7-7b30-41f0-88c5-a808d053f45c-0001 3: I1122 17:09:04.925025 17382 task_status_update_manager.cpp:538] Cleaning up status update stream for task e57b35e8-1889-450e-b0d0-d4386a38347a of framework 77e050e7-7b30-41f0-88c5-a808d053f45c-0001 3: I1122 17:09:04.925083 17379 gc.cpp:90] Scheduling '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMultipleFrameworks_1_FjwzLB/slaves/77e050e7-7b30-41f0-88c5-a808d053f45c-S0/frameworks/77e050e7-7b30-41f0-88c5-a808d053f45c-0001' for gc 6.99998929428148days in the future 3: I1122 17:09:04.925508 17362 slave.cpp:883] Agent terminating 3: I1122 17:09:04.925966 17370 master.cpp:1311] Agent 77e050e7-7b30-41f0-88c5-a808d053f45c-S0 at slave(865)@172.17.0.2:45570 (9701b45c60ec) disconnected 3: I1122 17:09:04.926013 17370 master.cpp:3370] Disconnecting agent 77e050e7-7b30-41f0-88c5-a808d053f45c-S0 at slave(865)@172.17.0.2:45570 (9701b45c60ec) 3: I1122 17:09:04.926115 17370 master.cpp:3389] Deactivating agent 77e050e7-7b30-41f0-88c5-a808d053f45c-S0 at slave(865)@172.17.0.2:45570 (9701b45c60ec) 3: I1122 17:09:04.926311 17377 hierarchical.cpp:697] Agent 77e050e7-7b30-41f0-88c5-a808d053f45c-S0 deactivated 3: I1122 17:09:04.936173 17362 master.cpp:1153] Master terminating 3: I1122 17:09:04.938251 17368 hierarchical.cpp:633] Removed agent 77e050e7-7b30-41f0-88c5-a808d053f45c-S0 3: [ OK ] DiskResource/PersistentVolumeTest.SharedPersistentVolumeMultipleFrameworks/1 (1115 ms) 3: [ RUN ] DiskResource/PersistentVolumeTest.SharedPersistentVolumeMasterFailover/0 3: I1122 17:09:04.951560 17362 cluster.cpp:162] Creating default 'local' authorizer 3: I1122 17:09:04.956547 17380 master.cpp:448] Master 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7 (9701b45c60ec) started on 172.17.0.2:45570 3: I1122 17:09:04.956583 17380 master.cpp:450] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/VRHdUE/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/VRHdUE/master" --zk_session_timeout="10secs" 3: I1122 17:09:04.956989 17380 master.cpp:499] Master only allowing authenticated frameworks to register 3: I1122 17:09:04.956998 17380 master.cpp:505] Master only allowing authenticated agents to register 3: I1122 17:09:04.957003 17380 master.cpp:511] Master only allowing authenticated HTTP frameworks to register 3: I1122 17:09:04.957012 17380 credentials.hpp:37] Loading credentials for authentication from '/tmp/VRHdUE/credentials' 3: I1122 17:09:04.957446 17380 master.cpp:555] Using default 'crammd5' authenticator 3: I1122 17:09:04.957653 17380 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1122 17:09:04.957864 17380 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1122 17:09:04.958034 17380 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1122 17:09:04.958190 17380 master.cpp:634] Authorization enabled 3: I1122 17:09:04.958431 17375 whitelist_watcher.cpp:77] No whitelist given 3: I1122 17:09:04.958437 17367 hierarchical.cpp:173] Initialized hierarchical allocator process 3: I1122 17:09:04.962909 17366 master.cpp:2215] Elected as the leading master! 3: I1122 17:09:04.962960 17366 master.cpp:1695] Recovering from registrar 3: I1122 17:09:04.963227 17385 registrar.cpp:347] Recovering registrar 3: I1122 17:09:04.964149 17385 registrar.cpp:391] Successfully fetched the registry (0B) in 857088ns 3: I1122 17:09:04.964356 17385 registrar.cpp:495] Applied 1 operations in 52429ns; attempting to update the registry 3: I1122 17:09:04.965265 17385 registrar.cpp:552] Successfully updated the registry in 816128ns 3: I1122 17:09:04.965453 17385 registrar.cpp:424] Successfully recovered registrar 3: I1122 17:09:04.966068 17386 master.cpp:1808] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register 3: I1122 17:09:04.966145 17374 hierarchical.cpp:211] Skipping recovery of hierarchical allocator: nothing to recover 3: W1122 17:09:04.978973 17362 process.cpp:2756] Attempted to spawn already running process [email protected]:45570 3: I1122 17:09:04.980689 17362 containerizer.cpp:301] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } 3: W1122 17:09:04.981525 17362 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges 3: W1122 17:09:04.981741 17362 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges 3: I1122 17:09:04.981809 17362 provisioner.cpp:259] Using default backend 'copy' 3: I1122 17:09:04.985173 17362 cluster.cpp:448] Creating default 'local' authorizer 3: I1122 17:09:04.989042 17381 slave.cpp:262] Mesos agent started on (866)@172.17.0.2:45570 3: W1122 17:09:04.989678 17362 process.cpp:2756] Attempted to spawn already running process [email protected]:45570 3: I1122 17:09:04.989084 17381 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_bk6yHL/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_bk6yHL/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io"; --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_bk6yHL/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/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_bk6yHL/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_bk6yHL/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":4096.0},"type":"SCALAR"}]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_bk6yHL" --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/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ" --zk_session_timeout="10secs" 3: I1122 17:09:04.989739 17381 credentials.hpp:86] Loading credential for authentication from '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_bk6yHL/credential' 3: I1122 17:09:04.990025 17381 slave.cpp:295] Agent using credential for: test-principal 3: I1122 17:09:04.990057 17381 credentials.hpp:37] Loading credentials for authentication from '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_bk6yHL/http_credentials' 3: I1122 17:09:04.990463 17381 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1122 17:09:04.990758 17381 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1122 17:09:04.991106 17362 sched.cpp:232] Version: 1.5.0 3: I1122 17:09:04.992377 17378 sched.cpp:336] New master detected at [email protected]:45570 3: I1122 17:09:04.992563 17378 sched.cpp:396] Authenticating with master [email protected]:45570 3: I1122 17:09:04.992589 17378 sched.cpp:403] Using default CRAM-MD5 authenticatee 3: I1122 17:09:04.993050 17383 authenticatee.cpp:121] Creating new client SASL connection 3: I1122 17:09:04.993613 17376 master.cpp:8306] Authenticating [email protected]:45570 3: I1122 17:09:04.993839 17385 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1544)@172.17.0.2:45570 3: I1122 17:09:04.994305 17377 authenticator.cpp:98] Creating new server SASL connection 3: I1122 17:09:04.994225 17381 slave.cpp:593] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":4096.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] 3: I1122 17:09:04.994663 17381 slave.cpp:601] Agent attributes: [ ] 3: I1122 17:09:04.994678 17381 slave.cpp:610] Agent hostname: 9701b45c60ec 3: I1122 17:09:04.994705 17371 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1122 17:09:04.994747 17371 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1122 17:09:04.995019 17374 authenticator.cpp:204] Received SASL authentication start 3: I1122 17:09:04.995115 17364 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1122 17:09:04.995129 17374 authenticator.cpp:326] Authentication requires more steps 3: I1122 17:09:04.995343 17384 authenticatee.cpp:259] Received SASL authentication step 3: I1122 17:09:04.995534 17386 authenticator.cpp:232] Received SASL authentication step 3: I1122 17:09:04.995579 17386 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9701b45c60ec' server FQDN: '9701b45c60ec' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1122 17:09:04.995596 17386 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1122 17:09:04.995657 17386 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1122 17:09:04.995685 17386 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9701b45c60ec' server FQDN: '9701b45c60ec' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1122 17:09:04.995700 17386 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1122 17:09:04.995707 17386 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1122 17:09:04.995728 17386 authenticator.cpp:318] Authentication success 3: I1122 17:09:04.995973 17367 authenticatee.cpp:299] Authentication success 3: I1122 17:09:04.996073 17363 master.cpp:8336] Successfully authenticated principal 'test-principal' at [email protected]:45570 3: I1122 17:09:04.996126 17386 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1544)@172.17.0.2:45570 3: I1122 17:09:04.996625 17373 sched.cpp:502] Successfully authenticated with master [email protected]:45570 3: I1122 17:09:04.996670 17373 sched.cpp:824] Sending SUBSCRIBE call to [email protected]:45570 3: I1122 17:09:04.996912 17373 sched.cpp:857] Will retry registration in 1.186941018secs if necessary 3: I1122 17:09:04.997242 17366 master.cpp:2963] Received SUBSCRIBE call for framework 'default' at [email protected]:45570 3: I1122 17:09:04.997373 17366 master.cpp:2280] Authorizing framework principal 'test-principal' to receive offers for roles '{ default-role }' 3: I1122 17:09:04.998021 17380 state.cpp:64] Recovering state from '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/meta' 3: I1122 17:09:04.998414 17383 task_status_update_manager.cpp:207] Recovering task status update manager 3: I1122 17:09:04.998410 17378 master.cpp:3043] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT, SHARED_RESOURCES ] 3: I1122 17:09:04.998692 17385 containerizer.cpp:668] Recovering containerizer 3: I1122 17:09:04.999388 17364 sched.cpp:751] Framework registered with 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:04.999460 17364 sched.cpp:765] Scheduler::registered took 35780ns 3: I1122 17:09:04.999719 17377 hierarchical.cpp:306] Added framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.000066 17377 hierarchical.cpp:1457] Performed allocation for 0 agents in 116440ns 3: I1122 17:09:05.001057 17375 provisioner.cpp:455] Provisioner recovery complete 3: I1122 17:09:05.001528 17380 slave.cpp:6449] Finished recovery 3: I1122 17:09:05.002676 17371 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1122 17:09:05.002692 17376 slave.cpp:1007] New master detected at [email protected]:45570 3: I1122 17:09:05.002861 17376 slave.cpp:1042] Detecting new master 3: I1122 17:09:05.004220 17374 slave.cpp:1069] Authenticating with master [email protected]:45570 3: I1122 17:09:05.004317 17374 slave.cpp:1078] Using default CRAM-MD5 authenticatee 3: I1122 17:09:05.004621 17384 authenticatee.cpp:121] Creating new client SASL connection 3: I1122 17:09:05.004981 17367 master.cpp:8306] Authenticating slave(866)@172.17.0.2:45570 3: I1122 17:09:05.005112 17363 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1545)@172.17.0.2:45570 3: I1122 17:09:05.005429 17386 authenticator.cpp:98] Creating new server SASL connection 3: I1122 17:09:05.005694 17364 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1122 17:09:05.005725 17364 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1122 17:09:05.005931 17369 authenticator.cpp:204] Received SASL authentication start 3: I1122 17:09:05.006026 17369 authenticator.cpp:326] Authentication requires more steps 3: I1122 17:09:05.006214 17379 authenticatee.cpp:259] Received SASL authentication step 3: I1122 17:09:05.006505 17382 authenticator.cpp:232] Received SASL authentication step 3: I1122 17:09:05.006572 17382 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9701b45c60ec' server FQDN: '9701b45c60ec' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1122 17:09:05.006602 17382 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1122 17:09:05.006661 17382 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1122 17:09:05.006708 17382 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9701b45c60ec' server FQDN: '9701b45c60ec' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1122 17:09:05.006732 17382 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1122 17:09:05.006757 17382 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1122 17:09:05.006789 17382 authenticator.cpp:318] Authentication success 3: I1122 17:09:05.006912 17385 authenticatee.cpp:299] Authentication success 3: I1122 17:09:05.007017 17366 master.cpp:8336] Successfully authenticated principal 'test-principal' at slave(866)@172.17.0.2:45570 3: I1122 17:09:05.007092 17385 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1545)@172.17.0.2:45570 3: I1122 17:09:05.007311 17377 slave.cpp:1161] Successfully authenticated with master [email protected]:45570 3: I1122 17:09:05.007668 17377 slave.cpp:1685] Will retry registration in 6.861859ms if necessary 3: I1122 17:09:05.008239 17372 master.cpp:6036] Received register agent message from slave(866)@172.17.0.2:45570 (9701b45c60ec) 3: I1122 17:09:05.008453 17372 master.cpp:3872] Authorizing agent with principal 'test-principal' 3: I1122 17:09:05.008991 17383 master.cpp:6098] Authorized registration of agent at slave(866)@172.17.0.2:45570 (9701b45c60ec) 3: I1122 17:09:05.009145 17383 master.cpp:6191] Registering agent at slave(866)@172.17.0.2:45570 (9701b45c60ec) with id 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 3: I1122 17:09:05.009800 17370 registrar.cpp:495] Applied 1 operations in 125962ns; attempting to update the registry 3: I1122 17:09:05.010633 17370 registrar.cpp:552] Successfully updated the registry in 753152ns 3: I1122 17:09:05.010911 17384 master.cpp:6240] Admitted agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 at slave(866)@172.17.0.2:45570 (9701b45c60ec) 3: I1122 17:09:05.011971 17363 slave.cpp:1207] Registered with master [email protected]:45570; given agent ID 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 3: I1122 17:09:05.012112 17369 task_status_update_manager.cpp:188] Resuming sending task status updates 3: I1122 17:09:05.012462 17363 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/meta/slaves/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0/slave.info' 3: I1122 17:09:05.011864 17384 master.cpp:6273] Registered agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 at slave(866)@172.17.0.2:45570 (9701b45c60ec) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":4096.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] 3: I1122 17:09:05.012711 17386 hierarchical.cpp:600] Added agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 (9701b45c60ec) with cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)]):4096; ports:[31000-32000] (allocated: {}) 3: I1122 17:09:05.013103 17363 slave.cpp:1298] Forwarding total oversubscribed resources {} 3: I1122 17:09:05.013394 17373 master.cpp:7085] Received update of agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 at slave(866)@172.17.0.2:45570 (9701b45c60ec) with total oversubscribed resources {} 3: I1122 17:09:05.013767 17373 master.cpp:7103] Ignoring update on agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 at slave(866)@172.17.0.2:45570 (9701b45c60ec) as it reports no changes 3: I1122 17:09:05.014789 17386 hierarchical.cpp:1457] Performed allocation for 1 agents in 1.861911ms 3: I1122 17:09:05.016022 17382 master.cpp:8136] Sending 1 offers to framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 (default) at [email protected]:45570 3: I1122 17:09:05.016795 17366 sched.cpp:921] Scheduler::resourceOffers took 145420ns 3: I1122 17:09:05.020120 17380 master.cpp:10045] Removing offer 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-O0 3: I1122 17:09:05.020431 17380 master.cpp:4230] Processing ACCEPT call for offers: [ 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-O0 ] on agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 at slave(866)@172.17.0.2:45570 (9701b45c60ec) for framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 (default) at [email protected]:45570 3: I1122 17:09:05.020617 17380 master.cpp:3792] Authorizing principal 'test-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"shared":{},"type":"SCALAR"}]' 3: I1122 17:09:05.021194 17380 master.cpp:3599] Authorizing framework principal 'test-principal' to launch task 53f619d3-bf74-4e9e-92dd-0ec2d09a2727 3: I1122 17:09:05.021482 17380 master.cpp:3599] Authorizing framework principal 'test-principal' to launch task 33ebb2e5-8a6d-428b-ab82-7591f2d583f1 3: I1122 17:09:05.023115 17367 master.cpp:4706] Applying CREATE operation for volumes [{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"shared":{},"type":"SCALAR"}] from framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 (default) at [email protected]:45570 to agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 at slave(866)@172.17.0.2:45570 (9701b45c60ec) 3: I1122 17:09:05.023829 17367 master.cpp:9990] Sending updated checkpointed resources disk(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048<1> to agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 at slave(866)@172.17.0.2:45570 (9701b45c60ec) 3: I1122 17:09:05.025929 17367 master.cpp:10793] Adding task 53f619d3-bf74-4e9e-92dd-0ec2d09a2727 with resources [{"allocation_info":{"role":"default-role"},"name":"cpus","scalar":{"value":0.5},"type":"SCALAR"},{"allocation_info":{"role":"default-role"},"name":"mem","scalar":{"value":512.0},"type":"SCALAR"},{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"shared":{},"type":"SCALAR"}] on agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 at slave(866)@172.17.0.2:45570 (9701b45c60ec) 3: I1122 17:09:05.026247 17369 slave.cpp:3590] Updated checkpointed resources from {} to disk(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048<1> 3: I1122 17:09:05.026793 17367 master.cpp:4978] Launching task 53f619d3-bf74-4e9e-92dd-0ec2d09a2727 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 (default) at [email protected]:45570 with resources [{"allocation_info":{"role":"default-role"},"name":"cpus","scalar":{"value":0.5},"type":"SCALAR"},{"allocation_info":{"role":"default-role"},"name":"mem","scalar":{"value":512.0},"type":"SCALAR"},{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"shared":{},"type":"SCALAR"}] on agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 at slave(866)@172.17.0.2:45570 (9701b45c60ec) 3: I1122 17:09:05.028198 17366 slave.cpp:1806] Got assigned task '53f619d3-bf74-4e9e-92dd-0ec2d09a2727' for framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.029233 17366 slave.cpp:2074] Authorizing task '53f619d3-bf74-4e9e-92dd-0ec2d09a2727' for framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.029328 17366 slave.cpp:7270] Authorizing framework principal 'test-principal' to launch task 53f619d3-bf74-4e9e-92dd-0ec2d09a2727 3: I1122 17:09:05.030303 17365 slave.cpp:2242] Launching task '53f619d3-bf74-4e9e-92dd-0ec2d09a2727' for framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.030169 17367 master.cpp:10793] Adding task 33ebb2e5-8a6d-428b-ab82-7591f2d583f1 with resources [{"allocation_info":{"role":"default-role"},"name":"cpus","scalar":{"value":0.5},"type":"SCALAR"},{"allocation_info":{"role":"default-role"},"name":"mem","scalar":{"value":512.0},"type":"SCALAR"},{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"shared":{},"type":"SCALAR"}] on agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 at slave(866)@172.17.0.2:45570 (9701b45c60ec) 3: I1122 17:09:05.030872 17367 master.cpp:4932] Allocating additional resources disk(allocated: default-role)(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048<1> for task 33ebb2e5-8a6d-428b-ab82-7591f2d583f1 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 (default) at [email protected]:45570 on agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 at slave(866)@172.17.0.2:45570 (9701b45c60ec) 3: I1122 17:09:05.031124 17365 paths.cpp:621] Trying to chown '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/slaves/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/53f619d3-bf74-4e9e-92dd-0ec2d09a2727/runs/6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30' to user 'mesos' 3: I1122 17:09:05.031036 17367 master.cpp:4978] Launching task 33ebb2e5-8a6d-428b-ab82-7591f2d583f1 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 (default) at [email protected]:45570 with resources [{"allocation_info":{"role":"default-role"},"name":"cpus","scalar":{"value":0.5},"type":"SCALAR"},{"allocation_info":{"role":"default-role"},"name":"mem","scalar":{"value":512.0},"type":"SCALAR"},{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"shared":{},"type":"SCALAR"}] on agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 at slave(866)@172.17.0.2:45570 (9701b45c60ec) 3: I1122 17:09:05.031471 17365 slave.cpp:7744] Launching executor '53f619d3-bf74-4e9e-92dd-0ec2d09a2727' of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 with resources [{"allocation_info":{"role":"default-role"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"default-role"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"}] in work directory '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/slaves/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/53f619d3-bf74-4e9e-92dd-0ec2d09a2727/runs/6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30' 3: I1122 17:09:05.032409 17365 slave.cpp:2933] Launching container 6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30 for executor '53f619d3-bf74-4e9e-92dd-0ec2d09a2727' of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.032758 17383 containerizer.cpp:1195] Starting container 6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30 3: I1122 17:09:05.032841 17365 slave.cpp:2470] Queued task '53f619d3-bf74-4e9e-92dd-0ec2d09a2727' for executor '53f619d3-bf74-4e9e-92dd-0ec2d09a2727' of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.033463 17365 slave.cpp:1806] Got assigned task '33ebb2e5-8a6d-428b-ab82-7591f2d583f1' for framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.033804 17383 containerizer.cpp:1367] Checkpointed ContainerConfig at '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_bk6yHL/containers/6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30/config' 3: I1122 17:09:05.033836 17383 containerizer.cpp:2919] Transitioning the state of container 6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30 from PROVISIONING to PREPARING 3: I1122 17:09:05.033957 17365 slave.cpp:958] Successfully attached '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/slaves/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/53f619d3-bf74-4e9e-92dd-0ec2d09a2727/runs/6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30' to virtual path '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/slaves/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/53f619d3-bf74-4e9e-92dd-0ec2d09a2727/runs/latest' 3: I1122 17:09:05.034014 17365 slave.cpp:958] Successfully attached '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/slaves/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/53f619d3-bf74-4e9e-92dd-0ec2d09a2727/runs/6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30' to virtual path '/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/53f619d3-bf74-4e9e-92dd-0ec2d09a2727/runs/latest' 3: I1122 17:09:05.034075 17365 slave.cpp:958] Successfully attached '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/slaves/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/53f619d3-bf74-4e9e-92dd-0ec2d09a2727/runs/6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30' to virtual path '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/slaves/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/53f619d3-bf74-4e9e-92dd-0ec2d09a2727/runs/6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30' 3: I1122 17:09:05.034219 17365 slave.cpp:2074] Authorizing task '33ebb2e5-8a6d-428b-ab82-7591f2d583f1' for framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.034304 17365 slave.cpp:7270] Authorizing framework principal 'test-principal' to launch task 33ebb2e5-8a6d-428b-ab82-7591f2d583f1 3: I1122 17:09:05.034544 17371 posix.cpp:204] Changing the ownership of the persistent volume at '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/volumes/roles/default-role/id1' with uid 1000 and gid 1000 3: I1122 17:09:05.034641 17371 posix.cpp:250] Adding symlink from '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/volumes/roles/default-role/id1' to '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/slaves/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/53f619d3-bf74-4e9e-92dd-0ec2d09a2727/runs/6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30/path1' for persistent volume disk(allocated: default-role)(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048 of container 6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30 3: I1122 17:09:05.035244 17370 slave.cpp:2242] Launching task '33ebb2e5-8a6d-428b-ab82-7591f2d583f1' for framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.035836 17370 paths.cpp:621] Trying to chown '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/slaves/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/33ebb2e5-8a6d-428b-ab82-7591f2d583f1/runs/a1e8eb4d-77a2-417e-8cf6-43ba83510158' to user 'mesos' 3: I1122 17:09:05.036109 17370 slave.cpp:7744] Launching executor '33ebb2e5-8a6d-428b-ab82-7591f2d583f1' of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 with resources [{"allocation_info":{"role":"default-role"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"default-role"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"}] in work directory '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/slaves/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/33ebb2e5-8a6d-428b-ab82-7591f2d583f1/runs/a1e8eb4d-77a2-417e-8cf6-43ba83510158' 3: I1122 17:09:05.036978 17370 slave.cpp:2933] Launching container a1e8eb4d-77a2-417e-8cf6-43ba83510158 for executor '33ebb2e5-8a6d-428b-ab82-7591f2d583f1' of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.037346 17370 slave.cpp:2470] Queued task '33ebb2e5-8a6d-428b-ab82-7591f2d583f1' for executor '33ebb2e5-8a6d-428b-ab82-7591f2d583f1' of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.037396 17369 containerizer.cpp:1195] Starting container a1e8eb4d-77a2-417e-8cf6-43ba83510158 3: I1122 17:09:05.037477 17370 slave.cpp:958] Successfully attached '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/slaves/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/33ebb2e5-8a6d-428b-ab82-7591f2d583f1/runs/a1e8eb4d-77a2-417e-8cf6-43ba83510158' to virtual path '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/slaves/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/33ebb2e5-8a6d-428b-ab82-7591f2d583f1/runs/latest' 3: I1122 17:09:05.037549 17370 slave.cpp:958] Successfully attached '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/slaves/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/33ebb2e5-8a6d-428b-ab82-7591f2d583f1/runs/a1e8eb4d-77a2-417e-8cf6-43ba83510158' to virtual path '/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/33ebb2e5-8a6d-428b-ab82-7591f2d583f1/runs/latest' 3: I1122 17:09:05.037612 17370 slave.cpp:958] Successfully attached '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/slaves/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/33ebb2e5-8a6d-428b-ab82-7591f2d583f1/runs/a1e8eb4d-77a2-417e-8cf6-43ba83510158' to virtual path '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/slaves/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/33ebb2e5-8a6d-428b-ab82-7591f2d583f1/runs/a1e8eb4d-77a2-417e-8cf6-43ba83510158' 3: I1122 17:09:05.038300 17369 containerizer.cpp:1367] Checkpointed ContainerConfig at '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_bk6yHL/containers/a1e8eb4d-77a2-417e-8cf6-43ba83510158/config' 3: I1122 17:09:05.038332 17369 containerizer.cpp:2919] Transitioning the state of container a1e8eb4d-77a2-417e-8cf6-43ba83510158 from PROVISIONING to PREPARING 3: I1122 17:09:05.038352 17372 hierarchical.cpp:856] Updated allocation of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 on agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 from cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)]):4096; ports(allocated: default-role):[31000-32000] to cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)]):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048<2> 3: I1122 17:09:05.038959 17375 posix.cpp:250] Adding symlink from '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/volumes/roles/default-role/id1' to '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/slaves/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/33ebb2e5-8a6d-428b-ab82-7591f2d583f1/runs/a1e8eb4d-77a2-417e-8cf6-43ba83510158/path1' for persistent volume disk(allocated: default-role)(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048 of container a1e8eb4d-77a2-417e-8cf6-43ba83510158 3: I1122 17:09:05.040400 17372 hierarchical.cpp:1132] Recovered cpus(allocated: default-role):1; mem(allocated: default-role):1024; disk(allocated: default-role)(reservations: [(STATIC,default-role)]):2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)]):2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048<1>, allocated: cpus(allocated: default-role):1; mem(allocated: default-role):1024; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048<2>) on agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 from framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.040501 17372 hierarchical.cpp:1178] Framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 filtered agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 for 5secs 3: I1122 17:09:05.040792 17369 containerizer.cpp:1836] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-executor","--launcher_dir=\/mesos\/build\/src"],"shell":false,"value":"\/mesos\/build\/src\/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","type":"VALUE","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","type":"VALUE","value":"172.17.0.2:45570"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"0"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"\/tmp\/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ\/slaves\/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0\/frameworks\/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000\/executors\/53f619d3-bf74-4e9e-92dd-0ec2d09a2727\/runs\/6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"53f619d3-bf74-4e9e-92dd-0ec2d09a2727"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave(866)@172.17.0.2:45570"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"\/tmp\/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ\/slaves\/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0\/frameworks\/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000\/executors\/53f619d3-bf74-4e9e-92dd-0ec2d09a2727\/runs\/6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30"}]},"task_environment":{},"user":"mesos","working_directory":"\/tmp\/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ\/slaves\/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0\/frameworks\/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000\/executors\/53f619d3-bf74-4e9e-92dd-0ec2d09a2727\/runs\/6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30"}" --pipe_read="15" --pipe_write="16" --runtime_directory="/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_bk6yHL/containers/6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30" --unshare_namespace_mnt="false"' 3: I1122 17:09:05.044450 17369 launcher.cpp:140] Forked child with pid '26529' for container '6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30' 3: I1122 17:09:05.045040 17369 containerizer.cpp:2919] Transitioning the state of container 6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30 from PREPARING to ISOLATING 3: I1122 17:09:05.046619 17369 containerizer.cpp:2919] Transitioning the state of container 6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30 from ISOLATING to FETCHING 3: I1122 17:09:05.046953 17368 fetcher.cpp:379] Starting to fetch URIs for container: 6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30, directory: /tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/slaves/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/53f619d3-bf74-4e9e-92dd-0ec2d09a2727/runs/6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30 3: I1122 17:09:05.048255 17369 containerizer.cpp:1836] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-executor","--launcher_dir=\/mesos\/build\/src"],"shell":false,"value":"\/mesos\/build\/src\/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","type":"VALUE","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","type":"VALUE","value":"172.17.0.2:45570"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"0"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"\/tmp\/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ\/slaves\/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0\/frameworks\/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000\/executors\/33ebb2e5-8a6d-428b-ab82-7591f2d583f1\/runs\/a1e8eb4d-77a2-417e-8cf6-43ba83510158"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"33ebb2e5-8a6d-428b-ab82-7591f2d583f1"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave(866)@172.17.0.2:45570"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"\/tmp\/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ\/slaves\/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0\/frameworks\/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000\/executors\/33ebb2e5-8a6d-428b-ab82-7591f2d583f1\/runs\/a1e8eb4d-77a2-417e-8cf6-43ba83510158"}]},"task_environment":{},"user":"mesos","working_directory":"\/tmp\/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ\/slaves\/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0\/frameworks\/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000\/executors\/33ebb2e5-8a6d-428b-ab82-7591f2d583f1\/runs\/a1e8eb4d-77a2-417e-8cf6-43ba83510158"}" --pipe_read="18" --pipe_write="19" --runtime_directory="/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_bk6yHL/containers/a1e8eb4d-77a2-417e-8cf6-43ba83510158" --unshare_namespace_mnt="false"' 3: I1122 17:09:05.051182 17369 launcher.cpp:140] Forked child with pid '26530' for container 'a1e8eb4d-77a2-417e-8cf6-43ba83510158' 3: I1122 17:09:05.051707 17369 containerizer.cpp:2919] Transitioning the state of container a1e8eb4d-77a2-417e-8cf6-43ba83510158 from PREPARING to ISOLATING 3: I1122 17:09:05.053444 17369 containerizer.cpp:2919] Transitioning the state of container 6bb8e16b-b334-4ce8-8bb7-08a4ea7bbd30 from FETCHING to RUNNING 3: I1122 17:09:05.053972 17369 containerizer.cpp:2919] Transitioning the state of container a1e8eb4d-77a2-417e-8cf6-43ba83510158 from ISOLATING to FETCHING 3: I1122 17:09:05.054162 17384 fetcher.cpp:379] Starting to fetch URIs for container: a1e8eb4d-77a2-417e-8cf6-43ba83510158, directory: /tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeMasterFailover_0_CX4UpJ/slaves/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0/frameworks/64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000/executors/33ebb2e5-8a6d-428b-ab82-7591f2d583f1/runs/a1e8eb4d-77a2-417e-8cf6-43ba83510158 3: I1122 17:09:05.055456 17371 containerizer.cpp:2919] Transitioning the state of container a1e8eb4d-77a2-417e-8cf6-43ba83510158 from FETCHING to RUNNING 3: I1122 17:09:05.353560 26552 exec.cpp:162] Version: 1.5.0 3: I1122 17:09:05.354544 26561 exec.cpp:162] Version: 1.5.0 3: I1122 17:09:05.368655 17375 slave.cpp:4066] Got registration for executor '33ebb2e5-8a6d-428b-ab82-7591f2d583f1' of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 from executor(1)@172.17.0.2:37909 3: I1122 17:09:05.370657 17375 slave.cpp:4066] Got registration for executor '53f619d3-bf74-4e9e-92dd-0ec2d09a2727' of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 from executor(1)@172.17.0.2:40541 3: I1122 17:09:05.373025 17375 slave.cpp:2672] Sending queued task '33ebb2e5-8a6d-428b-ab82-7591f2d583f1' to executor '33ebb2e5-8a6d-428b-ab82-7591f2d583f1' of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 at executor(1)@172.17.0.2:37909 3: I1122 17:09:05.374328 17368 slave.cpp:2672] Sending queued task '53f619d3-bf74-4e9e-92dd-0ec2d09a2727' to executor '53f619d3-bf74-4e9e-92dd-0ec2d09a2727' of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 at executor(1)@172.17.0.2:40541 3: I1122 17:09:05.377239 26572 exec.cpp:237] Executor registered on agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 3: I1122 17:09:05.377239 26575 exec.cpp:237] Executor registered on agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 3: I1122 17:09:05.383582 26537 executor.cpp:171] Received SUBSCRIBED event 3: I1122 17:09:05.385365 26537 executor.cpp:175] Subscribed executor on 9701b45c60ec 3: I1122 17:09:05.385779 26537 executor.cpp:171] Received LAUNCH event 3: I1122 17:09:05.384073 26536 executor.cpp:171] Received SUBSCRIBED event 3: I1122 17:09:05.386025 26536 executor.cpp:175] Subscribed executor on 9701b45c60ec 3: I1122 17:09:05.386322 26536 executor.cpp:171] Received LAUNCH event 3: I1122 17:09:05.388708 26537 executor.cpp:637] Starting task 33ebb2e5-8a6d-428b-ab82-7591f2d583f1 3: I1122 17:09:05.389269 26536 executor.cpp:637] Starting task 53f619d3-bf74-4e9e-92dd-0ec2d09a2727 3: I1122 17:09:05.393508 17382 slave.cpp:4520] Handling status update TASK_STARTING (UUID: 17d208b7-6f76-4c68-a93e-4e9e2b11c00b) for task 53f619d3-bf74-4e9e-92dd-0ec2d09a2727 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 from executor(1)@172.17.0.2:40541 3: I1122 17:09:05.394373 17379 slave.cpp:4520] Handling status update TASK_STARTING (UUID: cbe194cb-ee3b-462e-b2cf-93629a7ddc0b) for task 33ebb2e5-8a6d-428b-ab82-7591f2d583f1 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 from executor(1)@172.17.0.2:37909 3: I1122 17:09:05.396065 17377 task_status_update_manager.cpp:328] Received task status update TASK_STARTING (UUID: 17d208b7-6f76-4c68-a93e-4e9e2b11c00b) for task 53f619d3-bf74-4e9e-92dd-0ec2d09a2727 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.396134 17377 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 53f619d3-bf74-4e9e-92dd-0ec2d09a2727 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.397233 17377 task_status_update_manager.cpp:383] Forwarding task status update TASK_STARTING (UUID: 17d208b7-6f76-4c68-a93e-4e9e2b11c00b) for task 53f619d3-bf74-4e9e-92dd-0ec2d09a2727 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 to the agent 3: I1122 17:09:05.397650 17385 slave.cpp:5003] Forwarding the update TASK_STARTING (UUID: 17d208b7-6f76-4c68-a93e-4e9e2b11c00b) for task 53f619d3-bf74-4e9e-92dd-0ec2d09a2727 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 to [email protected]:45570 3: I1122 17:09:05.397687 17377 task_status_update_manager.cpp:328] Received task status update TASK_STARTING (UUID: cbe194cb-ee3b-462e-b2cf-93629a7ddc0b) for task 33ebb2e5-8a6d-428b-ab82-7591f2d583f1 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.397789 17377 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 33ebb2e5-8a6d-428b-ab82-7591f2d583f1 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.398108 17385 slave.cpp:4896] Task status update manager successfully handled status update TASK_STARTING (UUID: 17d208b7-6f76-4c68-a93e-4e9e2b11c00b) for task 53f619d3-bf74-4e9e-92dd-0ec2d09a2727 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.398216 17385 slave.cpp:4912] Sending acknowledgement for status update TASK_STARTING (UUID: 17d208b7-6f76-4c68-a93e-4e9e2b11c00b) for task 53f619d3-bf74-4e9e-92dd-0ec2d09a2727 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 to executor(1)@172.17.0.2:40541 3: I1122 17:09:05.398299 17377 task_status_update_manager.cpp:383] Forwarding task status update TASK_STARTING (UUID: cbe194cb-ee3b-462e-b2cf-93629a7ddc0b) for task 33ebb2e5-8a6d-428b-ab82-7591f2d583f1 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 to the agent 3: I1122 17:09:05.398439 17380 master.cpp:7268] Status update TASK_STARTING (UUID: 17d208b7-6f76-4c68-a93e-4e9e2b11c00b) for task 53f619d3-bf74-4e9e-92dd-0ec2d09a2727 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 from agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 at slave(866)@172.17.0.2:45570 (9701b45c60ec) 3: I1122 17:09:05.398536 17380 master.cpp:7330] Forwarding status update TASK_STARTING (UUID: 17d208b7-6f76-4c68-a93e-4e9e2b11c00b) for task 53f619d3-bf74-4e9e-92dd-0ec2d09a2727 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.398864 17385 slave.cpp:5003] Forwarding the update TASK_STARTING (UUID: cbe194cb-ee3b-462e-b2cf-93629a7ddc0b) for task 33ebb2e5-8a6d-428b-ab82-7591f2d583f1 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 to [email protected]:45570 3: I1122 17:09:05.398912 17380 master.cpp:9523] Updating the state of task 53f619d3-bf74-4e9e-92dd-0ec2d09a2727 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 (latest state: TASK_STARTING, status update state: TASK_STARTING) 3: I1122 17:09:05.399300 17385 slave.cpp:4896] Task status update manager successfully handled status update TASK_STARTING (UUID: cbe194cb-ee3b-462e-b2cf-93629a7ddc0b) for task 33ebb2e5-8a6d-428b-ab82-7591f2d583f1 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.399379 17376 sched.cpp:1029] Scheduler::statusUpdate took 175364ns 3: I1122 17:09:05.399400 17385 slave.cpp:4912] Sending acknowledgement for status update TASK_STARTING (UUID: cbe194cb-ee3b-462e-b2cf-93629a7ddc0b) for task 33ebb2e5-8a6d-428b-ab82-7591f2d583f1 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 to executor(1)@172.17.0.2:37909 3: I1122 17:09:05.399518 17372 master.cpp:7268] Status update TASK_STARTING (UUID: cbe194cb-ee3b-462e-b2cf-93629a7ddc0b) for task 33ebb2e5-8a6d-428b-ab82-7591f2d583f1 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 from agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 at slave(866)@172.17.0.2:45570 (9701b45c60ec) 3: I1122 17:09:05.399611 17372 master.cpp:7330] Forwarding status update TASK_STARTING (UUID: cbe194cb-ee3b-462e-b2cf-93629a7ddc0b) for task 33ebb2e5-8a6d-428b-ab82-7591f2d583f1 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.399901 17372 master.cpp:9523] Updating the state of task 33ebb2e5-8a6d-428b-ab82-7591f2d583f1 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 (latest state: TASK_STARTING, status update state: TASK_STARTING) 3: I1122 17:09:05.400241 17376 sched.cpp:1029] Scheduler::statusUpdate took 141562ns 3: I1122 17:09:05.400408 17372 master.cpp:5793] Processing ACKNOWLEDGE call 17d208b7-6f76-4c68-a93e-4e9e2b11c00b for task 53f619d3-bf74-4e9e-92dd-0ec2d09a2727 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 (default) at [email protected]:45570 on agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 3: I1122 17:09:05.400943 17384 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 17d208b7-6f76-4c68-a93e-4e9e2b11c00b) for task 53f619d3-bf74-4e9e-92dd-0ec2d09a2727 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 3: I1122 17:09:05.400928 17372 master.cpp:5793] Processing ACKNOWLEDGE call cbe194cb-ee3b-462e-b2cf-93629a7ddc0b for task 33ebb2e5-8a6d-428b-ab82-7591f2d583f1 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 (default) at [email protected]:45570 on agent 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-S0 3: I1122 17:09:05.401460 17364 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: cbe194cb-ee3b-462e-b2cf-93629a7ddc0b) for task 33ebb2e5-8a6d-428b-ab82-7591f2d583f1 of framework 64dc3a54-3d12-41d0-81c7-b8af1eac3fe7-0000 write /dev/stdout: resource temporarily unavailable Build step 'Execute shell' marked build as failure
