You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/10/06 20:29:50 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4295
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)/4295/display/redirect?page=changes>
Changes:
[vinodkone] Added `--disable-libtool-wrappers` to reviewbot.
------------------------------------------
[...truncated 30.58 MB...]
3: I1006 20:29:35.181102 17110 registrar.cpp:495] Applied 1 operations in 37645ns; attempting to update the registry
3: I1006 20:29:35.182468 17110 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1006 20:29:35.182821 17110 registrar.cpp:424] Successfully recovered registrar
3: I1006 20:29:35.183622 17111 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I1006 20:29:35.183677 17117 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
3: W1006 20:29:35.193090 17109 process.cpp:3194] Attempted to spawn already running process files@172.17.0.2:38429
3: I1006 20:29:35.194617 17109 containerizer.cpp:292] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1006 20:29:35.195343 17109 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges
3: W1006 20:29:35.195559 17109 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1006 20:29:35.195597 17109 provisioner.cpp:255] Using default backend 'copy'
3: I1006 20:29:35.198248 17109 cluster.cpp:448] Creating default 'local' authorizer
3: I1006 20:29:35.201606 17117 slave.cpp:254] Mesos agent started on (825)@172.17.0.2:38429
3: I1006 20:29:35.201632 17117 slave.cpp:255] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_6QbOhM/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_BadACLDropCreateAndDestroy_1_6QbOhM/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_BadACLDropCreateAndDestroy_1_6QbOhM/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_BadACLDropCreateAndDestroy_1_6QbOhM/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_BadACLDropCreateAndDestroy_1_6QbOhM/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"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_6QbOhM" --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_BadACLDropCreateAndDestroy_1_TKMZ9y" --zk_session_timeout="10secs"
3: I1006 20:29:35.202455 17117 credentials.hpp:86] Loading credential for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_6QbOhM/credential'
3: W1006 20:29:35.202522 17109 process.cpp:3194] Attempted to spawn already running process version@172.17.0.2:38429
3: I1006 20:29:35.202802 17117 slave.cpp:287] Agent using credential for: test-principal
3: I1006 20:29:35.203156 17117 credentials.hpp:37] Loading credentials for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_6QbOhM/http_credentials'
3: I1006 20:29:35.203665 17117 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1006 20:29:35.204071 17117 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1006 20:29:35.204068 17109 sched.cpp:232] Version: 1.5.0
3: I1006 20:29:35.205164 17116 sched.cpp:336] New master detected at master@172.17.0.2:38429
3: I1006 20:29:35.205550 17116 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1006 20:29:35.205577 17116 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.2:38429
3: I1006 20:29:35.205935 17116 sched.cpp:853] Will retry registration in 811.477126ms if necessary
3: I1006 20:29:35.206743 17115 master.cpp:2929] Received SUBSCRIBE call for framework 'default' at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.206856 17115 master.cpp:2263] Authorizing framework principal 'test-principal' to receive offers for roles '{ default-role }'
3: I1006 20:29:35.207837 17115 master.cpp:3009] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1006 20:29:35.209167 17111 sched.cpp:747] Framework registered with 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.209229 17111 sched.cpp:761] Scheduler::registered took 26987ns
3: I1006 20:29:35.209884 17110 hierarchical.cpp:303] Added framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.210358 17110 hierarchical.cpp:1943] No allocations performed
3: I1006 20:29:35.210409 17110 hierarchical.cpp:2033] No inverse offers to send out!
3: I1006 20:29:35.210736 17110 hierarchical.cpp:1486] Performed allocation for 0 agents in 455796ns
3: I1006 20:29:35.210747 17117 slave.cpp:585] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1006 20:29:35.211601 17117 slave.cpp:593] Agent attributes: [ ]
3: I1006 20:29:35.211621 17117 slave.cpp:602] Agent hostname: cbb5209f01f2
3: I1006 20:29:35.212076 17114 status_update_manager.cpp:177] Pausing sending status updates
3: I1006 20:29:35.214105 17116 state.cpp:64] Recovering state from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_TKMZ9y/meta'
3: I1006 20:29:35.214938 17116 status_update_manager.cpp:203] Recovering status update manager
3: I1006 20:29:35.215395 17115 containerizer.cpp:648] Recovering containerizer
3: I1006 20:29:35.217813 17116 provisioner.cpp:416] Provisioner recovery complete
3: I1006 20:29:35.218703 17116 slave.cpp:6326] Finished recovery
3: I1006 20:29:35.219638 17116 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1006 20:29:35.220314 17114 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1006 20:29:35.220930 17111 status_update_manager.cpp:177] Pausing sending status updates
3: I1006 20:29:35.221065 17113 slave.cpp:993] New master detected at master@172.17.0.2:38429
3: I1006 20:29:35.221151 17113 slave.cpp:1028] Detecting new master
3: I1006 20:29:35.223309 17117 slave.cpp:1055] Authenticating with master master@172.17.0.2:38429
3: I1006 20:29:35.223438 17117 slave.cpp:1064] Using default CRAM-MD5 authenticatee
3: I1006 20:29:35.223956 17112 authenticatee.cpp:121] Creating new client SASL connection
3: I1006 20:29:35.224210 17114 hierarchical.cpp:1943] No allocations performed
3: I1006 20:29:35.224498 17114 hierarchical.cpp:2033] No inverse offers to send out!
3: I1006 20:29:35.224668 17114 hierarchical.cpp:1486] Performed allocation for 0 agents in 523927ns
3: I1006 20:29:35.225141 17112 master.cpp:7936] Authenticating slave(825)@172.17.0.2:38429
3: I1006 20:29:35.225473 17116 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1501)@172.17.0.2:38429
3: I1006 20:29:35.226240 17116 authenticator.cpp:98] Creating new server SASL connection
3: I1006 20:29:35.226663 17117 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1006 20:29:35.226697 17117 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1006 20:29:35.226861 17116 authenticator.cpp:204] Received SASL authentication start
3: I1006 20:29:35.226970 17116 authenticator.cpp:326] Authentication requires more steps
3: I1006 20:29:35.227361 17116 authenticatee.cpp:259] Received SASL authentication step
3: I1006 20:29:35.227773 17115 authenticator.cpp:232] Received SASL authentication step
3: I1006 20:29:35.227829 17115 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'cbb5209f01f2' server FQDN: 'cbb5209f01f2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1006 20:29:35.227846 17115 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1006 20:29:35.227897 17115 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1006 20:29:35.227926 17115 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'cbb5209f01f2' server FQDN: 'cbb5209f01f2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1006 20:29:35.227946 17115 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1006 20:29:35.227958 17115 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1006 20:29:35.227982 17115 authenticator.cpp:318] Authentication success
3: I1006 20:29:35.228586 17117 authenticatee.cpp:299] Authentication success
3: I1006 20:29:35.228641 17114 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1501)@172.17.0.2:38429
3: I1006 20:29:35.229053 17117 slave.cpp:1147] Successfully authenticated with master master@172.17.0.2:38429
3: I1006 20:29:35.229238 17115 master.cpp:7966] Successfully authenticated principal 'test-principal' at slave(825)@172.17.0.2:38429
3: I1006 20:29:35.229540 17117 slave.cpp:1642] Will retry registration in 5.738173ms if necessary
3: I1006 20:29:35.230072 17116 master.cpp:5801] Received register agent message from slave(825)@172.17.0.2:38429 (cbb5209f01f2)
3: I1006 20:29:35.230473 17116 master.cpp:3838] Authorizing agent with principal 'test-principal'
3: I1006 20:29:35.231480 17111 master.cpp:5861] Authorized registration of agent at slave(825)@172.17.0.2:38429 (cbb5209f01f2)
3: I1006 20:29:35.231729 17111 master.cpp:5954] Registering agent at slave(825)@172.17.0.2:38429 (cbb5209f01f2) with id 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0
3: I1006 20:29:35.232625 17110 registrar.cpp:495] Applied 1 operations in 113686ns; attempting to update the registry
3: I1006 20:29:35.234043 17114 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1006 20:29:35.234694 17114 master.cpp:6001] Admitted agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2)
3: I1006 20:29:35.236400 17114 master.cpp:6032] Registered agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1006 20:29:35.237485 17110 hierarchical.cpp:593] Added agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 (cbb5209f01f2) with cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; ports:[31000-32000] (allocated: {})
3: I1006 20:29:35.237488 17115 slave.cpp:4982] Received ping from slave-observer(748)@172.17.0.2:38429
3: I1006 20:29:35.238518 17115 slave.cpp:1193] Registered with master master@172.17.0.2:38429; given agent ID 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0
3: I1006 20:29:35.238760 17117 status_update_manager.cpp:184] Resuming sending status updates
3: I1006 20:29:35.239274 17115 slave.cpp:1213] Checkpointing SlaveInfo to '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_TKMZ9y/meta/slaves/9d35fd6e-82ff-4346-95cd-09b305f5de22-S0/slave.info'
3: I1006 20:29:35.240164 17115 slave.cpp:1263] Forwarding total resources cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; ports:[31000-32000]
3: I1006 20:29:35.240512 17115 slave.cpp:1272] Forwarding total oversubscribed resources {}
3: I1006 20:29:35.241766 17111 master.cpp:6804] Received update of agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2) with total resources cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; ports:[31000-32000]
3: I1006 20:29:35.241930 17111 master.cpp:6817] Received update of agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2) with total oversubscribed resources {}
3: I1006 20:29:35.242316 17110 hierarchical.cpp:2033] No inverse offers to send out!
3: I1006 20:29:35.242393 17110 hierarchical.cpp:1486] Performed allocation for 1 agents in 3.967429ms
3: I1006 20:29:35.242581 17111 master.cpp:6828] Ignoring update on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2) as it reports no changes
3: I1006 20:29:35.244681 17111 master.cpp:7766] Sending 1 offers to framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.246028 17114 sched.cpp:917] Scheduler::resourceOffers took 144022ns
3: I1006 20:29:35.249299 17116 master.cpp:9389] Removing offer 9d35fd6e-82ff-4346-95cd-09b305f5de22-O0
3: I1006 20:29:35.249675 17116 master.cpp:4196] Processing ACCEPT call for offers: [ 9d35fd6e-82ff-4346-95cd-09b305f5de22-O0 ] on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2) for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.250192 17116 master.cpp:3758] Authorizing principal 'test-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1006 20:29:35.252581 17113 master.cpp:2348] Dropping CREATE offer operation from framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429: Not authorized to create persistent volumes as 'test-principal'
3: I1006 20:29:35.255237 17116 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; ports:[31000-32000], allocated: {}) on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 from framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.261708 17114 master.cpp:7766] Sending 1 offers to framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.262960 17114 sched.cpp:917] Scheduler::resourceOffers took 147411ns
3: I1006 20:29:35.263175 17115 hierarchical.cpp:2033] No inverse offers to send out!
3: I1006 20:29:35.263556 17115 hierarchical.cpp:1486] Performed allocation for 1 agents in 6.123907ms
3: I1006 20:29:35.265013 17117 master.cpp:5207] Processing DECLINE call for offers: [ 9d35fd6e-82ff-4346-95cd-09b305f5de22-O1 ] for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: W1006 20:29:35.265087 17109 process.cpp:3194] Attempted to spawn already running process version@172.17.0.2:38429
3: I1006 20:29:35.266319 17109 sched.cpp:232] Version: 1.5.0
3: I1006 20:29:35.266959 17117 master.cpp:9389] Removing offer 9d35fd6e-82ff-4346-95cd-09b305f5de22-O1
3: I1006 20:29:35.267468 17117 master.cpp:3439] Processing SUPPRESS call for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.267560 17111 sched.cpp:336] New master detected at master@172.17.0.2:38429
3: I1006 20:29:35.267777 17111 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1006 20:29:35.267971 17111 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.2:38429
3: I1006 20:29:35.268348 17111 sched.cpp:853] Will retry registration in 1.21136591secs if necessary
3: I1006 20:29:35.268926 17112 master.cpp:2929] Received SUBSCRIBE call for framework 'creator-framework' at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429
3: I1006 20:29:35.269037 17112 master.cpp:2263] Authorizing framework principal 'creator-principal' to receive offers for roles '{ default-role }'
3: I1006 20:29:35.270081 17114 master.cpp:3009] Subscribing framework creator-framework with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1006 20:29:35.270943 17116 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; ports:[31000-32000], allocated: {}) on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 from framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.271188 17114 sched.cpp:747] Framework registered with 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001
3: I1006 20:29:35.271250 17114 sched.cpp:761] Scheduler::registered took 26587ns
3: I1006 20:29:35.271611 17116 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.272104 17116 hierarchical.cpp:303] Added framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001
3: I1006 20:29:35.275085 17116 hierarchical.cpp:2033] No inverse offers to send out!
3: I1006 20:29:35.275161 17116 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.798322ms
3: I1006 20:29:35.276614 17111 master.cpp:7766] Sending 1 offers to framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 (creator-framework) at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429
3: I1006 20:29:35.277799 17111 sched.cpp:917] Scheduler::resourceOffers took 190439ns
3: I1006 20:29:35.280061 17112 hierarchical.cpp:1943] No allocations performed
3: I1006 20:29:35.280123 17112 hierarchical.cpp:2033] No inverse offers to send out!
3: I1006 20:29:35.280215 17112 hierarchical.cpp:1486] Performed allocation for 1 agents in 297859ns
3: I1006 20:29:35.283974 17114 master.cpp:9389] Removing offer 9d35fd6e-82ff-4346-95cd-09b305f5de22-O2
3: I1006 20:29:35.284226 17114 master.cpp:4196] Processing ACCEPT call for offers: [ 9d35fd6e-82ff-4346-95cd-09b305f5de22-O2 ] on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2) for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 (creator-framework) at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429
3: I1006 20:29:35.285233 17114 master.cpp:3758] Authorizing principal 'creator-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: I1006 20:29:35.288524 17110 master.cpp:4639] Applying CREATE operation for volumes [{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}] from framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 (creator-framework) at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429 to agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2)
3: I1006 20:29:35.289897 17110 master.cpp:9335] Sending updated checkpointed resources disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048 to agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2)
3: I1006 20:29:35.292007 17117 slave.cpp:3535] Updated checkpointed resources from {} to disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048
3: I1006 20:29:35.297365 17115 hierarchical.cpp:887] Updated allocation of framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 from cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; ports(allocated: default-role):[31000-32000] to cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048
3: I1006 20:29:35.300225 17115 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048, allocated: {}) on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 from framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001
3: I1006 20:29:35.305269 17111 hierarchical.cpp:2033] No inverse offers to send out!
3: I1006 20:29:35.305356 17111 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.946905ms
3: I1006 20:29:35.307260 17114 master.cpp:7766] Sending 1 offers to framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 (creator-framework) at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429
3: I1006 20:29:35.308341 17112 sched.cpp:917] Scheduler::resourceOffers took 151240ns
3: I1006 20:29:35.309506 17117 master.cpp:5207] Processing DECLINE call for offers: [ 9d35fd6e-82ff-4346-95cd-09b305f5de22-O3 ] for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 (creator-framework) at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429
3: I1006 20:29:35.311262 17117 master.cpp:9389] Removing offer 9d35fd6e-82ff-4346-95cd-09b305f5de22-O3
3: I1006 20:29:35.311753 17117 master.cpp:3439] Processing SUPPRESS call for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 (creator-framework) at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429
3: I1006 20:29:35.313063 17115 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048, allocated: {}) on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 from framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001
3: I1006 20:29:35.313385 17115 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001
3: I1006 20:29:35.314357 17113 master.cpp:5312] Processing REVIVE call for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.314777 17113 hierarchical.cpp:1308] Revived offers for roles { default-role } of framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.317988 17113 hierarchical.cpp:2033] No inverse offers to send out!
3: I1006 20:29:35.318066 17113 hierarchical.cpp:1486] Performed allocation for 1 agents in 3.099458ms
3: I1006 20:29:35.319684 17113 master.cpp:7766] Sending 1 offers to framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.320772 17112 sched.cpp:917] Scheduler::resourceOffers took 153235ns
3: I1006 20:29:35.323637 17117 master.cpp:9389] Removing offer 9d35fd6e-82ff-4346-95cd-09b305f5de22-O4
3: I1006 20:29:35.323945 17117 master.cpp:4196] Processing ACCEPT call for offers: [ 9d35fd6e-82ff-4346-95cd-09b305f5de22-O4 ] on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2) for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.324448 17117 master.cpp:3810] Authorizing principal 'test-principal' to destroy volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1006 20:29:35.326570 17112 master.cpp:2348] Dropping DESTROY offer operation from framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429: Not authorized to destroy persistent volumes as 'test-principal'
3: I1006 20:29:35.329557 17110 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048, allocated: {}) on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 from framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.334460 17116 hierarchical.cpp:2033] No inverse offers to send out!
3: I1006 20:29:35.334542 17116 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.745358ms
3: I1006 20:29:35.336156 17111 master.cpp:7766] Sending 1 offers to framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.337285 17117 sched.cpp:917] Scheduler::resourceOffers took 154147ns
3: I1006 20:29:35.337851 17109 sched.cpp:2005] Asked to stop the driver
3: I1006 20:29:35.338058 17114 sched.cpp:1187] Stopping framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.338778 17112 master.cpp:8447] Processing TEARDOWN call for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.338830 17112 master.cpp:8459] Removing framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.338806 17109 sched.cpp:2005] Asked to stop the driver
3: I1006 20:29:35.338855 17112 master.cpp:3299] Deactivating framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.338932 17114 sched.cpp:1187] Stopping framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001
3: I1006 20:29:35.339640 17117 hierarchical.cpp:412] Deactivated framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.340931 17112 master.cpp:9389] Removing offer 9d35fd6e-82ff-4346-95cd-09b305f5de22-O5
3: I1006 20:29:35.342025 17114 slave.cpp:3227] Asked to shut down framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 by master@172.17.0.2:38429
3: I1006 20:29:35.342077 17114 slave.cpp:3242] Cannot shut down unknown framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.342121 17112 master.cpp:8447] Processing TEARDOWN call for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 (creator-framework) at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429
3: I1006 20:29:35.342209 17112 master.cpp:8459] Removing framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 (creator-framework) at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429
3: I1006 20:29:35.342232 17112 master.cpp:3299] Deactivating framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 (creator-framework) at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429
3: I1006 20:29:35.343060 17111 slave.cpp:3227] Asked to shut down framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 by master@172.17.0.2:38429
3: I1006 20:29:35.343101 17111 slave.cpp:3242] Cannot shut down unknown framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001
3: I1006 20:29:35.343498 17117 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048, allocated: {}) on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 from framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.344095 17117 hierarchical.cpp:355] Removed framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.344192 17117 hierarchical.cpp:412] Deactivated framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001
3: I1006 20:29:35.344679 17117 hierarchical.cpp:355] Removed framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001
3: I1006 20:29:35.345099 17116 slave.cpp:869] Agent terminating
3: I1006 20:29:35.345624 17116 master.cpp:1303] Agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2) disconnected
3: I1006 20:29:35.345662 17116 master.cpp:3336] Disconnecting agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2)
3: I1006 20:29:35.345736 17116 master.cpp:3355] Deactivating agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2)
3: I1006 20:29:35.346079 17110 hierarchical.cpp:690] Agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 deactivated
3: I1006 20:29:35.354081 17109 master.cpp:1145] Master terminating
3: I1006 20:29:35.356003 17110 hierarchical.cpp:626] Removed agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0
3: [ OK ] DiskResource/PersistentVolumeTest.BadACLDropCreateAndDestroy/1 (200 ms)
3: [----------] 34 tests from DiskResource/PersistentVolumeTest (17122 ms total)
3:
3: [----------] Global test environment tear-down
3: [==========] 1641 tests from 178 test cases ran. (541348 ms total)
3: [ PASSED ] 1639 tests.
3: [ FAILED ] 2 tests, listed below:
3: [ FAILED ] OversubscriptionTest.RescindRevocableOfferWithIncreasedRevocable
3: [ FAILED ] PersistentVolumeEndpointsTest.DeleteNonExistentVolume
3:
3: 2 FAILED TESTS
3: YOU HAVE 21 DISABLED TESTS
3:
3: I1006 20:29:35.502277 17118 process.cpp:1068] Failed to accept socket: future discarded
3/3 Test #3: MesosTests .......................***Failed 556.65 sec
67% tests passed, 1 tests failed out of 3
Total Test time (real) = 595.39 sec
The following tests FAILED:
3 - MesosTests (Failed)
Errors while running CTest
make[3]: *** [CMakeFiles/check] Error 8
make[3]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[2]: Leaving directory `/mesos/build'
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1507316260-3283
Untagged: mesos-1507316260-3283:latest
Deleted: sha256:f29faafeec5082c0f4c0632ae6c11d29bcba7198b141f3cee9decb2ebe68cb7d
Deleted: sha256:7739d9f8f29c3952fae700657eaae1ff0244018fe7aa8203aa742f4c10b2cf5d
Deleted: sha256:4bcdeeff75c4341210109959ce529a65a66c69b9bee2cbeaa6bdb0828482f9f7
Deleted: sha256:06b23bacdab63a0cb9ae071b614f5390440b95fbb753eb14fe88e72ad5234447
Deleted: sha256:184fd6f7ca35323f5b96236219c75d39960065a73c9739dd6c131c0f8584f8ea
Deleted: sha256:0824497018f825d22fde23b7f76dd315b07d71e79063627be4ef664d3ee03c77
Deleted: sha256:b007fe9e6bd0c8319c84b4cda5a381375c9c4e86701483845b846817fed19f2d
Deleted: sha256:c31a02f0f32f1167c29ccc1618451be915175628b4debd8c0a66cf4a740ca479
Deleted: sha256:6d8c05205edfd72c27465cf777a211b40cfd2c41655d868f1c970bf4ffc0639a
Deleted: sha256:78039d9fb5b97c6f61e517f54e0b21ef86504b7d82aebc43838d5ed78b414998
Deleted: sha256:687d53aeef9a967cfeb4b23455ab11b727d9b5eff6f098d56af54425b1b39321
Deleted: sha256:a878e2c8114da3ec1ea4ee4f6cd9f5824e21d95a4064f06cfdc8912fc788de13
Deleted: sha256:07aef77acdd0b5fb821d11c8bbbf569f9254990500639fb9d9c48ca418a244eb
Deleted: sha256:114d0350e10df52088c7433661173afbdd1a076125412caac41b8dda87762c8b
Deleted: sha256:cb894da38e5298e04577cc4b9a190185d30037d760036a51849a514751f3e958
Deleted: sha256:bc9773f322d6d4d8c69979a65fd515142bf49b7d50f1b74e4a3e9a9d60965f1e
Deleted: sha256:b8d4ec4774441bcd17bbf148534ca649f72df632ccd73ef3d028b16a958c3ca5
Deleted: sha256:dea8818a72142188f37f030ceefd09f99caeb57bcc0b577a78cea01bb40e9555
Deleted: sha256:0a0ae8f9932a1dc309e30207461f291d05503099431253100fd8fb5d380f9ee2
Deleted: sha256:78c74a8d811888411750fddff6248ccfaff46a1b40a86c74df2114cfde699c8d
Deleted: sha256:0fa89b9fa912e9cfadea3df85138a4a8a4d4262d14ff13ee48936614d6da5fa0
Deleted: sha256:7d1601021d46aafef15f78b6561f6a23eceeddc4fb933c07c1152baffeadfcd2
Deleted: sha256:3edfd5121c70a47bc145270bced4012895a32162a625a9165ca754d54c35332e
Deleted: sha256:f171f124a4c042df9807c15de46eec410f1d1e3d99c722229fc6a73fdd8f3f63
Deleted: sha256:17f66a27fa8d456d4fdb408862dba507bddfacdf48cdb1b8ef2713aac8001841
Deleted: sha256:54c8364f003a16b3b634bc3117f7ee8d23f2a2d073666f3975f993dd400c8ce0
Deleted: sha256:92e1e2da2386d25ed783d65be4f186a546e59cc5d73a328199d26c5b739ffaf5
Deleted: sha256:33a8de27e44450801d96a7812c50b53f6422b7b545c2d733d8d4f96bf6bf6cc5
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4300
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4300/display/redirect?page=changes>
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4299
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4299/display/redirect?page=changes>
Changes:
[xujyan] Fixed a typo.
------------------------------------------
[...truncated 15.39 MB...]
3: I1010 02:47:36.088771 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 365607ns
3: I1010 02:47:37.090771 19004 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:37.090857 19004 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:37.090909 19004 hierarchical.cpp:1486] Performed allocation for 3 agents in 364024ns
3: I1010 02:47:37.409543 19001 slave.cpp:5943] Current disk usage 62.55%. Max allowed age: 1.921761054819236days
3: I1010 02:47:37.415374 18999 slave.cpp:5943] Current disk usage 62.55%. Max allowed age: 1.921761054819236days
3: I1010 02:47:37.420125 19004 slave.cpp:5943] Current disk usage 62.55%. Max allowed age: 1.921761054819236days
3: I1010 02:47:38.092911 19002 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:38.092990 19002 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:38.093050 19002 hierarchical.cpp:1486] Performed allocation for 3 agents in 361788ns
3: I1010 02:47:38.124037 19006 slave.cpp:4982] Received ping from slave-observer(1)@172.17.0.2:35697
3: I1010 02:47:38.200477 18999 slave.cpp:4982] Received ping from slave-observer(2)@172.17.0.2:35697
3: I1010 02:47:38.297032 19006 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:47:38.297381 19006 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:47:38.301306 19000 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:47:38.301957 19000 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:47:38.302443 19005 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:47:38.302773 19006 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:47:38.546504 19004 slave.cpp:4982] Received ping from slave-observer(3)@172.17.0.2:35697
3: I1010 02:47:39.094475 19004 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:39.094558 19004 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:39.094611 19004 hierarchical.cpp:1486] Performed allocation for 3 agents in 367061ns
3: I1010 02:47:40.095610 19002 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:40.095696 19002 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:40.095749 19002 hierarchical.cpp:1486] Performed allocation for 3 agents in 371121ns
3: I1010 02:47:41.097618 19003 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:41.097826 19003 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:41.097945 19003 hierarchical.cpp:1486] Performed allocation for 3 agents in 535564ns
3: I1010 02:47:42.100173 19000 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:42.100260 19000 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:42.100313 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 371212ns
3: I1010 02:47:43.101243 19006 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:43.101327 19006 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:43.101380 19006 hierarchical.cpp:1486] Performed allocation for 3 agents in 365788ns
3: I1010 02:47:44.103586 18999 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:44.103673 18999 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:44.103726 18999 hierarchical.cpp:1486] Performed allocation for 3 agents in 368965ns
3: I1010 02:47:45.105840 19005 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:45.105924 19005 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:45.105976 19005 hierarchical.cpp:1486] Performed allocation for 3 agents in 357407ns
3: I1010 02:47:46.107184 19001 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:46.107269 19001 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:46.107321 19001 hierarchical.cpp:1486] Performed allocation for 3 agents in 364755ns
3: I1010 02:47:47.108502 19004 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:47.108588 19004 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:47.108642 19004 hierarchical.cpp:1486] Performed allocation for 3 agents in 368765ns
3: I1010 02:47:48.110417 19005 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:48.110505 19005 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:48.110558 19005 hierarchical.cpp:1486] Performed allocation for 3 agents in 370721ns
3: I1010 02:47:49.112233 19001 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:49.112318 19001 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:49.112371 19001 hierarchical.cpp:1486] Performed allocation for 3 agents in 363091ns
3: I1010 02:47:50.114329 19004 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:50.114411 19004 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:50.114545 19004 hierarchical.cpp:1486] Performed allocation for 3 agents in 375292ns
3: I1010 02:47:51.116082 19002 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:51.116170 19002 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:51.116225 19002 hierarchical.cpp:1486] Performed allocation for 3 agents in 372405ns
3: I1010 02:47:52.118445 19003 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:52.118531 19003 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:52.118584 19003 hierarchical.cpp:1486] Performed allocation for 3 agents in 368034ns
3: I1010 02:47:53.120323 19000 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:53.120410 19000 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:53.120463 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 362590ns
3: I1010 02:47:53.124423 19005 slave.cpp:4982] Received ping from slave-observer(1)@172.17.0.2:35697
3: I1010 02:47:53.201663 19003 slave.cpp:4982] Received ping from slave-observer(2)@172.17.0.2:35697
3: I1010 02:47:53.298028 19005 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:47:53.298343 19005 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:47:53.302753 19001 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:47:53.303261 18999 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:47:53.304044 19004 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:47:53.304503 19000 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:47:53.547999 18999 slave.cpp:4982] Received ping from slave-observer(3)@172.17.0.2:35697
3: I1010 02:47:54.122232 18999 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:54.122316 18999 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:54.122370 18999 hierarchical.cpp:1486] Performed allocation for 3 agents in 367643ns
3: I1010 02:47:55.123236 19000 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:55.123317 19000 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:55.123369 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 353918ns
3: I1010 02:47:56.125133 19006 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:56.125211 19006 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:56.125264 19006 hierarchical.cpp:1486] Performed allocation for 3 agents in 357366ns
3: I1010 02:47:57.126636 19004 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:57.126719 19004 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:57.126773 19004 hierarchical.cpp:1486] Performed allocation for 3 agents in 368895ns
3: I1010 02:47:58.128167 19005 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:58.128248 19005 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:58.128300 19005 hierarchical.cpp:1486] Performed allocation for 3 agents in 355683ns
3: I1010 02:47:59.129472 19001 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:59.129552 19001 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:59.129606 19001 hierarchical.cpp:1486] Performed allocation for 3 agents in 362640ns
3: I1010 02:48:00.131108 19003 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:00.131188 19003 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:00.131242 19003 hierarchical.cpp:1486] Performed allocation for 3 agents in 363833ns
3: I1010 02:48:01.133388 19002 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:01.133472 19002 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:01.133525 19002 hierarchical.cpp:1486] Performed allocation for 3 agents in 369547ns
3: I1010 02:48:02.135215 18999 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:02.135298 18999 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:02.135351 18999 hierarchical.cpp:1486] Performed allocation for 3 agents in 369056ns
3: I1010 02:48:03.136426 19000 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:03.136507 19000 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:03.136560 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 364625ns
3: I1010 02:48:04.137852 19006 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:04.137934 19006 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:04.137989 19006 hierarchical.cpp:1486] Performed allocation for 3 agents in 373467ns
3: I1010 02:48:05.140218 19004 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:05.140301 19004 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:05.140354 19004 hierarchical.cpp:1486] Performed allocation for 3 agents in 360585ns
3: I1010 02:48:06.141855 19005 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:06.141937 19005 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:06.141988 19005 hierarchical.cpp:1486] Performed allocation for 3 agents in 364123ns
3: I1010 02:48:07.143498 19001 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:07.143579 19001 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:07.143632 19001 hierarchical.cpp:1486] Performed allocation for 3 agents in 366951ns
3: I1010 02:48:08.125128 19004 slave.cpp:4982] Received ping from slave-observer(1)@172.17.0.2:35697
3: I1010 02:48:08.145548 19002 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:08.145629 19002 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:08.145683 19002 hierarchical.cpp:1486] Performed allocation for 3 agents in 362730ns
3: I1010 02:48:08.202636 19001 slave.cpp:4982] Received ping from slave-observer(2)@172.17.0.2:35697
3: I1010 02:48:08.299340 19003 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:48:08.299708 19000 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:48:08.303968 19002 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:48:08.304247 19002 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:48:08.305127 18999 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:48:08.305593 18999 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:48:08.548729 19002 slave.cpp:4982] Received ping from slave-observer(3)@172.17.0.2:35697
3: I1010 02:48:09.147385 19002 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:09.147464 19002 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:09.147518 19002 hierarchical.cpp:1486] Performed allocation for 3 agents in 361989ns
3: I1010 02:48:10.149201 19003 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:10.149282 19003 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:10.149334 19003 hierarchical.cpp:1486] Performed allocation for 3 agents in 364064ns
3: I1010 02:48:11.151334 19005 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:11.151415 19005 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:11.151468 19005 hierarchical.cpp:1486] Performed allocation for 3 agents in 363903ns
3: I1010 02:48:12.152627 19001 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:12.152707 19001 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:12.152776 19001 hierarchical.cpp:1486] Performed allocation for 3 agents in 376896ns
3: I1010 02:48:13.153898 18999 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:13.153982 18999 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:13.154036 18999 hierarchical.cpp:1486] Performed allocation for 3 agents in 365919ns
3: I1010 02:48:14.156201 19006 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:14.156286 19006 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:14.156338 19006 hierarchical.cpp:1486] Performed allocation for 3 agents in 366941ns
3: I1010 02:48:15.157241 19004 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:15.157325 19004 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:15.157380 19004 hierarchical.cpp:1486] Performed allocation for 3 agents in 366840ns
3: I1010 02:48:16.158956 19000 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:16.159035 19000 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:16.159088 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 363342ns
3: I1010 02:48:17.160208 19002 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:17.160290 19002 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:17.160343 19002 hierarchical.cpp:1486] Performed allocation for 3 agents in 368305ns
3: I1010 02:48:18.161676 19003 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:18.162168 19003 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:18.162737 19003 hierarchical.cpp:1486] Performed allocation for 3 agents in 1.278859ms
3: I1010 02:48:19.164005 19005 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:19.164084 19005 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:19.164137 19005 hierarchical.cpp:1486] Performed allocation for 3 agents in 359282ns
3: I1010 02:48:20.165484 19001 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:20.165565 19001 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:20.165617 19001 hierarchical.cpp:1486] Performed allocation for 3 agents in 360003ns
3: I1010 02:48:21.166957 18999 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:21.167038 18999 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:21.167093 18999 hierarchical.cpp:1486] Performed allocation for 3 agents in 362911ns
3: I1010 02:48:22.168972 19006 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:22.169055 19006 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:22.169108 19006 hierarchical.cpp:1486] Performed allocation for 3 agents in 367623ns
3: I1010 02:48:23.125710 19001 slave.cpp:4982] Received ping from slave-observer(1)@172.17.0.2:35697
3: I1010 02:48:23.170521 19000 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:23.170601 19000 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:23.170655 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 357437ns
3: I1010 02:48:23.203562 19006 slave.cpp:4982] Received ping from slave-observer(2)@172.17.0.2:35697
3: I1010 02:48:23.300115 19004 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:48:23.300477 18999 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:48:23.304533 19000 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:48:23.304801 19000 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:48:23.306205 19006 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:48:23.306545 19004 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:48:23.549381 19005 slave.cpp:4982] Received ping from slave-observer(3)@172.17.0.2:35697
3: I1010 02:48:24.172621 19005 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:24.172706 19005 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:24.172760 19005 hierarchical.cpp:1486] Performed allocation for 3 agents in 357758ns
3: I1010 02:48:25.174675 19004 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:25.174759 19004 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:25.174813 19004 hierarchical.cpp:1486] Performed allocation for 3 agents in 368745ns
3: I1010 02:48:26.177160 19000 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:26.177243 19000 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:26.177296 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 365478ns
3: I1010 02:48:27.178969 19002 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:27.179049 19002 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:27.179105 19002 hierarchical.cpp:1486] Performed allocation for 3 agents in 366239ns
3: I1010 02:48:28.180590 19003 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:28.180671 19003 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:28.180732 19003 hierarchical.cpp:1486] Performed allocation for 3 agents in 373647ns
3: I1010 02:48:29.181849 19006 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:29.181927 19006 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:29.181982 19006 hierarchical.cpp:1486] Performed allocation for 3 agents in 356474ns
3: I1010 02:48:30.183920 19002 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:30.184005 19002 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:30.184056 19002 hierarchical.cpp:1486] Performed allocation for 3 agents in 358078ns
3: I1010 02:48:31.185343 19003 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:31.185427 19003 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:31.185482 19003 hierarchical.cpp:1486] Performed allocation for 3 agents in 364114ns
3: I1010 02:48:32.186897 19006 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:32.186982 19006 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:32.187034 19006 hierarchical.cpp:1486] Performed allocation for 3 agents in 365797ns
3: I1010 02:48:33.188400 19001 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:33.188488 19001 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:33.188540 19001 hierarchical.cpp:1486] Performed allocation for 3 agents in 372595ns
3: I1010 02:48:34.190479 18999 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:34.190565 18999 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:34.190618 18999 hierarchical.cpp:1486] Performed allocation for 3 agents in 369066ns
3: I1010 02:48:35.192786 19005 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:35.192875 19005 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:35.192929 19005 hierarchical.cpp:1486] Performed allocation for 3 agents in 372284ns
3: I1010 02:48:36.194901 19004 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:36.194988 19004 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:36.195041 19004 hierarchical.cpp:1486] Performed allocation for 3 agents in 366840ns
3: I1010 02:48:37.197008 19000 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:37.197094 19000 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:37.197146 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 360465ns
3: I1010 02:48:37.410290 19002 slave.cpp:5943] Current disk usage 62.55%. Max allowed age: 1.921755797146979days
3: I1010 02:48:37.416132 19004 slave.cpp:5943] Current disk usage 62.55%. Max allowed age: 1.921755797146979days
3: I1010 02:48:37.421315 18999 slave.cpp:5943] Current disk usage 62.55%. Max allowed age: 1.921755797146979days
3: I1010 02:48:38.126310 19000 slave.cpp:4982] Received ping from slave-observer(1)@172.17.0.2:35697
3: I1010 02:48:38.198206 19001 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:38.198292 19001 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:38.198345 19001 hierarchical.cpp:1486] Performed allocation for 3 agents in 369207ns
3: I1010 02:48:38.204442 19004 slave.cpp:4982] Received ping from slave-observer(2)@172.17.0.2:35697
3: I1010 02:48:38.301004 19000 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:48:38.301353 19002 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:48:38.305315 19001 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:48:38.305630 18999 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:48:38.307826 19003 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:48:38.308096 19000 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:48:38.549860 18999 slave.cpp:4982] Received ping from slave-observer(3)@172.17.0.2:35697
3: I1010 02:48:39.199925 19003 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:39.200006 19003 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:39.200059 19003 hierarchical.cpp:1486] Performed allocation for 3 agents in 354219ns
3: I1010 02:48:40.201997 19000 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:40.202083 19000 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:40.202136 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 367071ns
Build timed out (after 300 minutes). Marking the build as failed.
Build was aborted
++ docker rmi mesos-1507586086-17701
Error response from daemon: conflict: unable to remove repository reference "mesos-1507586086-17701" (must force) - container b72113a2d4d2 is using its referenced image 74333fd99b15
Not sending mail to unregistered user xujyan@apple.com
Re: Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4298
Posted by Vinod Kone <vi...@apache.org>.
+Greg, Benjamin
Is this new?
On Mon, Oct 9, 2017 at 7:34 AM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmak
> e,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)/4298/display/redirect?page=changes>
>
> Changes:
>
> [bbannier] Formatted 'synchronized' like loop instead of function.
>
> ------------------------------------------
> [...truncated 3.51 MB...]
> 3: I1009 14:32:44.304908 17239 slave.cpp:944] Successfully attached
> '/tmp/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover
> _MU8K9N/slaves/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0/
> frameworks/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000/executo
> rs/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d/runs/26a5d7b2-5ad5-44d5-9c14-e574764a51f8'
> to virtual path '/tmp/FaultToleranceTest_Updat
> eFrameworkInfoOnMasterFailover_MU8K9N/slaves/9b9b3770-d6be-
> 4958-bc33-cff10ae0de17-S0/frameworks/9b9b3770-d6be-4958-
> bc33-cff10ae0de17-0000/executors/cb78dc3a-717e-4a2d-b6f7-
> fb80c0ba9f9d/runs/26a5d7b2-5ad5-44d5-9c14-e574764a51f8'
> 3: I1009 14:32:44.308506 17253 containerizer.cpp:1720] 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":{"variabl
> es":[{"name":"LIBPROCESS_PORT","type":"VALUE","value":"0"},{
> "name":"MESOS_AGENT_ENDPOINT","type":"VALUE","value":"172.17.0.2:45196
> "},{"name":"MESOS_CHECKPOINT","type":"VALUE","va
> lue":"1"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"
> \/tmp\/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailove
> r_MU8K9N\/slaves\/9b9b3770-d6be-4958-bc33-cff10ae0de17-
> S0\/frameworks\/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000\/
> executors\/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d\/runs\/
> 26a5d7b2-5ad5-44d5-9c14-e574764a51f8"},{"name":"MESOS_
> EXECUTOR_ID","type":"VALUE","value":"cb78dc3a-717e-4a2d-
> b6f7-fb80c0ba9f9d"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_
> PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAME
> WORK_ID","type":"VALUE","value":"9b9b3770-d6be-4958-bc33-
> cff10ae0de17-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","
> type":"VALUE","value":"0"},{"name":"MESOS_RECOVERY_TIMEOUT"
> ,"type":"VALUE","value":"15mins"},{"name":"MESOS_SLAVE_
> ID","type":"VALUE","value":"9b9b3770-d6be-4958-bc33-
> cff10ae0de17-S0"},{"name":"MESOS_SLAVE_PID","type":"
> VALUE","value":"slave(59)@172.17.0.2:45196"},{"name":"MESOS_
> SUBSCRIPTION_BACKOFF_MAX","type":"VALUE","value":"2secs"}
> ,{"name":"MESOS_SANDBOX","type":"VALUE","value":"\/tmp\/
> FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_
> MU8K9N\/slaves\/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0\/
> frameworks\/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000\/
> executors\/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d\/runs\/
> 26a5d7b2-5ad5-44d5-9c14-e574764a51f8"}]},"task_
> environment":{},"user":"mesos","working_directory":"\/tmp\/
> FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_
> MU8K9N\/slaves\/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0\/
> frameworks\/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000\/
> executors\/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d\/runs\/
> 26a5d7b2-5ad5-44d5-9c14-e574764a51f8"}" --pipe_read="7" --pipe_write="8"
> --runtime_directory="/tmp/FaultToleranceTest_UpdateFramework
> InfoOnMasterFailover_j7yD9F/containers/26a5d7b2-5ad5-44d5-9c14-e574764a51f8"
> --unshare_namespace_mnt="false"'
> 3: I1009 14:32:44.310649 17253 launcher.cpp:140] Forked child with pid
> '18173' for container '26a5d7b2-5ad5-44d5-9c14-e574764a51f8'
> 3: I1009 14:32:44.310770 17253 containerizer.cpp:1812] Checkpointing
> container's forked pid 18173 to '/tmp/FaultToleranceTest_Updat
> eFrameworkInfoOnMasterFailover_MU8K9N/meta/slaves/9b9b3770-
> d6be-4958-bc33-cff10ae0de17-S0/frameworks/9b9b3770-d6be-
> 4958-bc33-cff10ae0de17-0000/executors/cb78dc3a-717e-4a2d-
> b6f7-fb80c0ba9f9d/runs/26a5d7b2-5ad5-44d5-9c14-
> e574764a51f8/pids/forked.pid'
> 3: I1009 14:32:44.311444 17253 containerizer.cpp:2751] Transitioning the
> state of container 26a5d7b2-5ad5-44d5-9c14-e574764a51f8 from PREPARING to
> ISOLATING
> 3: I1009 14:32:44.312573 17257 containerizer.cpp:2751] Transitioning the
> state of container 26a5d7b2-5ad5-44d5-9c14-e574764a51f8 from ISOLATING to
> FETCHING
> 3: I1009 14:32:44.312727 17252 fetcher.cpp:377] Starting to fetch URIs for
> container: 26a5d7b2-5ad5-44d5-9c14-e574764a51f8, directory:
> /tmp/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_
> MU8K9N/slaves/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0/
> frameworks/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000/executo
> rs/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d/runs/26a5d7b2-
> 5ad5-44d5-9c14-e574764a51f8
> 3: I1009 14:32:44.313786 17239 containerizer.cpp:2751] Transitioning the
> state of container 26a5d7b2-5ad5-44d5-9c14-e574764a51f8 from FETCHING to
> RUNNING
> 3: I1009 14:32:44.545111 18187 exec.cpp:162] Version: 1.5.0
> 3: I1009 14:32:44.556907 17250 slave.cpp:3957] Got registration for
> executor 'cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d' of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 from executor(1)@
> 172.17.0.2:36164
> 3: I1009 14:32:44.557358 17250 slave.cpp:4043] Checkpointing executor pid
> 'executor(1)@172.17.0.2:36164' to '/tmp/FaultToleranceTest_Updat
> eFrameworkInfoOnMasterFailover_MU8K9N/meta/slaves/9b9b3770-
> d6be-4958-bc33-cff10ae0de17-S0/frameworks/9b9b3770-d6be-
> 4958-bc33-cff10ae0de17-0000/executors/cb78dc3a-717e-4a2d-
> b6f7-fb80c0ba9f9d/runs/26a5d7b2-5ad5-44d5-9c14-
> e574764a51f8/pids/libprocess.pid'
> 3: I1009 14:32:44.559736 17259 slave.cpp:2629] Sending queued task
> 'cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d' to executor
> 'cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d' of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 at executor(1)@172.17.0.2:36164
> 3: I1009 14:32:44.561986 18193 exec.cpp:237] Executor registered on agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0
> 3: I1009 14:32:44.565585 18198 executor.cpp:171] Received SUBSCRIBED event
> 3: I1009 14:32:44.567385 18198 executor.cpp:175] Subscribed executor on
> 4c36cea139f4
> 3: I1009 14:32:44.567728 18198 executor.cpp:171] Received LAUNCH event
> 3: I1009 14:32:44.567924 18198 executor.cpp:633] Starting task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d
> 3: I1009 14:32:44.590138 18198 executor.cpp:477] Running
> '/mesos/build/src/mesos-containerizer launch <POSSIBLY-SENSITIVE-DATA>'
> 3: I1009 14:32:44.593616 18198 executor.cpp:646] Forked command at 18200
> 3: I1009 14:32:44.602321 17253 slave.cpp:4411] Handling status update
> TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 from executor(1)@
> 172.17.0.2:36164
> 3: I1009 14:32:44.605000 17261 status_update_manager.cpp:323] Received
> status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9)
> for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.605087 17261 status_update_manager.cpp:500] Creating
> StatusUpdate stream for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of
> framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.606317 17261 status_update_manager.cpp:834]
> Checkpointing UPDATE for status update TASK_RUNNING (UUID:
> 5903d32e-5574-45f8-91f8-1c385908dbc9) for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.606812 17261 status_update_manager.cpp:377] Forwarding
> update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 to the agent
> 3: I1009 14:32:44.607204 17251 slave.cpp:4892] Forwarding the update
> TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 to master@172.17.0.2:45196
> 3: I1009 14:32:44.607499 17251 slave.cpp:4786] Status update manager
> successfully handled status update TASK_RUNNING (UUID:
> 5903d32e-5574-45f8-91f8-1c385908dbc9) for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.607583 17251 slave.cpp:4802] Sending acknowledgement for
> status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9)
> for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 to executor(1)@172.17.0.2:36164
> 3: I1009 14:32:44.607857 17248 master.cpp:6993] Status update TASK_RUNNING
> (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 from agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196
> (4c36cea139f4)
> 3: I1009 14:32:44.607956 17248 master.cpp:7055] Forwarding status update
> TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.608155 17248 master.cpp:9157] Updating the state of task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 (latest state: TASK_RUNNING,
> status update state: TASK_RUNNING)
> 3: I1009 14:32:44.608521 17258 sched.cpp:1025] Scheduler::statusUpdate
> took 142398ns
> 3: I1009 14:32:44.608929 17245 master.cpp:5566] Processing ACKNOWLEDGE
> call 5903d32e-5574-45f8-91f8-1c385908dbc9 for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 (Framework 1) at
> scheduler-e2a439e4-2854-437f-a947-f5b320448e5f@172.17.0.2:45196 on agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0
> 3: I1009 14:32:44.609356 17253 status_update_manager.cpp:395] Received
> status update acknowledgement (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9)
> for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.609527 17253 status_update_manager.cpp:834]
> Checkpointing ACK for status update TASK_RUNNING (UUID:
> 5903d32e-5574-45f8-91f8-1c385908dbc9) for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.609925 17253 slave.cpp:3695] Status update manager
> successfully handled status update acknowledgement (UUID:
> 5903d32e-5574-45f8-91f8-1c385908dbc9) for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.610273 17239 master.cpp:1145] Master terminating
> 3: W1009 14:32:44.610399 17239 master.cpp:9237] Removing task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d with resources
> [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"va
> lue":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},
> "name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"
> allocation_info":{"role":"*"},"name":"disk","scalar":{"value
> ":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},
> "name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
> of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 on agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196
> (4c36cea139f4) in non-removable state TASK_RUNNING
> 3: I1009 14:32:44.610939 17250 hierarchical.cpp:626] Removed agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0
> 3: I1009 14:32:44.611913 17250 hierarchical.cpp:355] Removed framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.612026 17259 slave.cpp:5024] Got exited event for
> master@172.17.0.2:45196
> 3: W1009 14:32:44.612049 17259 slave.cpp:5029] Master disconnected!
> Waiting for a new master to be elected
> 3: I1009 14:32:44.615108 17237 sched.cpp:2005] Asked to stop the driver
> 3: I1009 14:32:44.615205 17251 sched.cpp:1187] Stopping framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.617620 17237 cluster.cpp:162] Creating default 'local'
> authorizer
> 3: I1009 14:32:44.620221 17240 master.cpp:445] Master
> 45991738-966f-4f70-8c5f-f555b5c45469 (4c36cea139f4) started on
> 172.17.0.2:45196
> 3: I1009 14:32:44.620241 17240 master.cpp:447] 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/H8OT9V/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/H8OT9V/master" --zk_session_timeout="10secs"
> 3: I1009 14:32:44.620631 17240 master.cpp:496] Master only allowing
> authenticated frameworks to register
> 3: I1009 14:32:44.620643 17240 master.cpp:502] Master only allowing
> authenticated agents to register
> 3: I1009 14:32:44.620651 17240 master.cpp:508] Master only allowing
> authenticated HTTP frameworks to register
> 3: I1009 14:32:44.620661 17240 credentials.hpp:37] Loading credentials for
> authentication from '/tmp/H8OT9V/credentials'
> 3: I1009 14:32:44.621049 17240 master.cpp:552] Using default 'crammd5'
> authenticator
> 3: I1009 14:32:44.621263 17240 http.cpp:1045] Creating default 'basic'
> HTTP authenticator for realm 'mesos-master-readonly'
> 3: I1009 14:32:44.621451 17240 http.cpp:1045] Creating default 'basic'
> HTTP authenticator for realm 'mesos-master-readwrite'
> 3: I1009 14:32:44.621611 17240 http.cpp:1045] Creating default 'basic'
> HTTP authenticator for realm 'mesos-master-scheduler'
> 3: I1009 14:32:44.621762 17240 master.cpp:631] Authorization enabled
> 3: I1009 14:32:44.621953 17258 whitelist_watcher.cpp:77] No whitelist given
> 3: I1009 14:32:44.622021 17245 hierarchical.cpp:171] Initialized
> hierarchical allocator process
> 3: I1009 14:32:44.625402 17244 master.cpp:2198] Elected as the leading
> master!
> 3: I1009 14:32:44.625428 17244 master.cpp:1687] Recovering from registrar
> 3: I1009 14:32:44.625560 17242 registrar.cpp:347] Recovering registrar
> 3: I1009 14:32:44.626109 17242 registrar.cpp:391] Successfully fetched the
> registry (0B) in 515840ns
> 3: I1009 14:32:44.626219 17242 registrar.cpp:495] Applied 1 operations in
> 28313ns; attempting to update the registry
> 3: I1009 14:32:44.626760 17242 registrar.cpp:552] Successfully updated the
> registry in 489728ns
> 3: I1009 14:32:44.626868 17242 registrar.cpp:424] Successfully recovered
> registrar
> 3: I1009 14:32:44.627198 17261 master.cpp:1791] Recovered 0 agents from
> the registry (129B); allowing 10mins for agents to re-register
> 3: I1009 14:32:44.627213 17258 hierarchical.cpp:209] Skipping recovery of
> hierarchical allocator: nothing to recover
> 3: I1009 14:32:44.627843 17250 status_update_manager.cpp:177] Pausing
> sending status updates
> 3: I1009 14:32:44.627868 17238 slave.cpp:993] New master detected at
> master@172.17.0.2:45196
> 3: I1009 14:32:44.627955 17238 slave.cpp:1028] Detecting new master
> 3: I1009 14:32:44.634227 17239 slave.cpp:1055] Authenticating with master
> master@172.17.0.2:45196
> 3: I1009 14:32:44.634287 17239 slave.cpp:1064] Using default CRAM-MD5
> authenticatee
> 3: I1009 14:32:44.634569 17244 authenticatee.cpp:121] Creating new client
> SASL connection
> 3: I1009 14:32:44.634912 17260 master.cpp:7936] Authenticating slave(59)@
> 172.17.0.2:45196
> 3: I1009 14:32:44.635037 17259 authenticator.cpp:414] Starting
> authentication session for crammd5-authenticatee(137)@172.17.0.2:45196
> 3: I1009 14:32:44.635282 17255 authenticator.cpp:98] Creating new server
> SASL connection
> 3: I1009 14:32:44.635511 17252 authenticatee.cpp:213] Received SASL
> authentication mechanisms: CRAM-MD5
> 3: I1009 14:32:44.635537 17252 authenticatee.cpp:239] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> 3: I1009 14:32:44.635627 17252 authenticator.cpp:204] Received SASL
> authentication start
> 3: I1009 14:32:44.635682 17252 authenticator.cpp:326] Authentication
> requires more steps
> 3: I1009 14:32:44.635807 17249 authenticatee.cpp:259] Received SASL
> authentication step
> 3: I1009 14:32:44.635953 17254 authenticator.cpp:232] Received SASL
> authentication step
> 3: I1009 14:32:44.635988 17254 auxprop.cpp:109] Request to lookup
> properties for user: 'test-principal' realm: '4c36cea139f4' server FQDN:
> '4c36cea139f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> 3: I1009 14:32:44.636006 17254 auxprop.cpp:181] Looking up auxiliary
> property '*userPassword'
> 3: I1009 14:32:44.636054 17254 auxprop.cpp:181] Looking up auxiliary
> property '*cmusaslsecretCRAM-MD5'
> 3: I1009 14:32:44.636081 17254 auxprop.cpp:109] Request to lookup
> properties for user: 'test-principal' realm: '4c36cea139f4' server FQDN:
> '4c36cea139f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> 3: I1009 14:32:44.636096 17254 auxprop.cpp:131] Skipping auxiliary
> property '*userPassword' since SASL_AUXPROP_AUTHZID == true
> 3: I1009 14:32:44.636104 17254 auxprop.cpp:131] Skipping auxiliary
> property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> 3: I1009 14:32:44.636124 17254 authenticator.cpp:318] Authentication
> success
> 3: I1009 14:32:44.636229 17246 authenticatee.cpp:299] Authentication
> success
> 3: I1009 14:32:44.636298 17241 master.cpp:7966] Successfully authenticated
> principal 'test-principal' at slave(59)@172.17.0.2:45196
> 3: I1009 14:32:44.636328 17242 authenticator.cpp:432] Authentication
> session cleanup for crammd5-authenticatee(137)@172.17.0.2:45196
> 3: I1009 14:32:44.636513 17251 slave.cpp:1147] Successfully authenticated
> with master master@172.17.0.2:45196
> 3: I1009 14:32:44.637054 17251 slave.cpp:1642] Will retry registration in
> 1.416192ms if necessary
> 3: *** Aborted at 1507559564 (unix time) try "date -d @1507559564" if you
> are using GNU date ***
> 3: I1009 14:32:44.637579 17248 master.cpp:6119] Received re-register agent
> message from agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@
> 172.17.0.2:45196 (4c36cea139f4)
> 3: I1009 14:32:44.637825 17248 master.cpp:3838] Authorizing agent with
> principal 'test-principal'
> 3: I1009 14:32:44.638353 17253 master.cpp:6188] Authorized re-registration
> of agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@
> 172.17.0.2:45196 (4c36cea139f4)
> 3: I1009 14:32:44.638433 17253 master.cpp:6324] Re-registering agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196
> (4c36cea139f4)
> 3: W1009 14:32:44.638944 17256 master.hpp:2224] Allowing UNKNOWN agent to
> reregister: hostname: "4c36cea139f4"
> 3: resources {
> 3: name: "cpus"
> 3: type: SCALAR
> 3: scalar {
> 3: value: 2
> 3: }
> 3: role: "*"
> 3: }
> 3: resources {
> 3: name: "mem"
> 3: type: SCALAR
> 3: scalar {
> 3: value: 1024
> 3: }
> 3: role: "*"
> 3: }
> 3: resources {
> 3: name: "disk"
> 3: type: SCALAR
> 3: scalar {
> 3: value: 1024
> 3: }
> 3: role: "*"
> 3: }
> 3: resources {
> 3: name: "ports"
> 3: type: RANGES
> 3: ranges {
> 3: range {
> 3: begin: 31000
> 3: end: 32000
> 3: }
> 3: }
> 3: role: "*"
> 3: }
> 3: id {
> 3: value: "9b9b3770-d6be-4958-bc33-cff10ae0de17-S0"
> 3: }
> 3: checkpoint: true
> 3: port: 45196
> 3: I1009 14:32:44.639232 17256 registrar.cpp:495] Applied 1 operations in
> 336929ns; attempting to update the registry
> 3: PC: @ 0x2b686836a104 process::EventQueue::Consumer::empty()
> 3: *** SIGSEGV (@0x8) received by PID 17237 (TID 0x2b687496e700) from PID
> 8; stack trace: ***
> 3: I1009 14:32:44.639811 17250 slave.cpp:1642] Will retry registration in
> 21.825496ms if necessary
> 3: @ 0x2b6868ad5330 (unknown)
> 3: I1009 14:32:44.639966 17256 registrar.cpp:552] Successfully updated the
> registry in 667136ns
> 3: I1009 14:32:44.640117 17260 master.cpp:6084] Ignoring re-register agent
> message from agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@
> 172.17.0.2:45196 (4c36cea139f4) as re-registration is already in progress
> 3: I1009 14:32:44.640242 17260 master.cpp:6393] Re-admitted agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196
> (4c36cea139f4)
> 3: I1009 14:32:44.640743 17260 master.cpp:10135] Adding task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d with resources
> [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"va
> lue":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},
> "name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"
> allocation_info":{"role":"*"},"name":"disk","scalar":{"value
> ":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},
> "name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
> on agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@
> 172.17.0.2:45196 (4c36cea139f4)
> 3: I1009 14:32:44.641433 17255 slave.cpp:4982] Received ping from
> slave-observer(53)@172.17.0.2:45196
> 3: I1009 14:32:44.641490 17260 master.cpp:6571] Re-registered agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196
> (4c36cea139f4) with [{"name":"cpus","scalar":{"val
> ue":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":
> 1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":
> 1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[
> {"begin":31000,"end":32000}]},"type":"RANGES"}]
> 3: I1009 14:32:44.641749 17260 master.cpp:6654] Recovering framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 from re-registering agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196
> (4c36cea139f4)
> 3: I1009 14:32:44.641808 17255 slave.cpp:1313] Re-registered with master
> master@172.17.0.2:45196
> 3: I1009 14:32:44.641847 17257 hierarchical.cpp:593] Added agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 (4c36cea139f4) with cpus:2;
> mem:1024; disk:1024; ports:[31000-32000] (allocated: cpus(allocated: *):2;
> mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated:
> *):[31000-32000])
> 3: I1009 14:32:44.641917 17249 status_update_manager.cpp:184] Resuming
> sending status updates
> 3: I1009 14:32:44.641906 17255 slave.cpp:1351] Forwarding total resources
> cpus:2; mem:1024; disk:1024; ports:[31000-32000]
> 3: I1009 14:32:44.641985 17255 slave.cpp:1360] Forwarding total
> oversubscribed resources {}
> 3: I1009 14:32:44.642164 17257 hierarchical.cpp:1943] No allocations
> performed
> 3: I1009 14:32:44.642222 17257 hierarchical.cpp:1486] Performed allocation
> for 1 agents in 176354ns
> 3: @ 0x2b686836a104 process::EventQueue::Consumer::empty()
> 3: I1009 14:32:44.642460 17260 master.cpp:6694] Sending updated
> checkpointed resources {} to agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0
> at slave(59)@172.17.0.2:45196 (4c36cea139f4)
> 3: I1009 14:32:44.642587 17242 slave.cpp:3462] Ignoring new checkpointed
> resources identical to the current version: {}
> 3: W1009 14:32:44.642791 17237 process.cpp:3194] Attempted to spawn
> already running process version@172.17.0.2:45196
> 3: I1009 14:32:44.642801 17260 master.cpp:6804] Received update of agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196
> (4c36cea139f4) with total resources cpus:2; mem:1024; disk:1024;
> ports:[31000-32000]
> 3: I1009 14:32:44.642875 17260 master.cpp:6817] Received update of agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196
> (4c36cea139f4) with total oversubscribed resources {}
> 3: I1009 14:32:44.643102 17260 master.cpp:6828] Ignoring update on agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196
> (4c36cea139f4) as it reports no changes
> 3: I1009 14:32:44.643549 17261 hierarchical.cpp:303] Added framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.643568 17237 sched.cpp:232] Version: 1.5.0
> 3: I1009 14:32:44.643597 17261 hierarchical.cpp:412] Deactivated framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: @ 0x2b686834ffbe process::ProcessManager::resume()
> 3: I1009 14:32:44.644179 17250 sched.cpp:336] New master detected at
> master@172.17.0.2:45196
> 3: I1009 14:32:44.644290 17250 sched.cpp:396] Authenticating with master
> master@172.17.0.2:45196
> 3: I1009 14:32:44.644306 17250 sched.cpp:403] Using default CRAM-MD5
> authenticatee
> 3: @ 0x2b686834c6f8 _ZZN7process14ProcessManager12
> init_threadsEvENKUlvE_clEv
> 3: I1009 14:32:44.644542 17256 authenticatee.cpp:121] Creating new client
> SASL connection
> 3: I1009 14:32:44.644791 17252 master.cpp:7936] Authenticating
> scheduler-2f192676-9d98-441e-981c-e6bbacc57852@172.17.0.2:45196
> 3: @ 0x2b686835defa _ZNSt12_Bind_simpleIFZN7proces
> s14ProcessManager12init_threadsEvEUlvE_vEE9_M_
> invokeIIEEEvSt12_Index_tupleIIXspT_EEE
> 3: I1009 14:32:44.644897 17247 authenticator.cpp:414] Starting
> authentication session for crammd5-authenticatee(138)@172.17.0.2:45196
> 3: I1009 14:32:44.645148 17249 authenticator.cpp:98] Creating new server
> SASL connection
> 3: @ 0x2b686835de51 _ZNSt12_Bind_simpleIFZN7proces
> s14ProcessManager12init_threadsEvEUlvE_vEEclEv
> 3: I1009 14:32:44.645354 17254 authenticatee.cpp:213] Received SASL
> authentication mechanisms: CRAM-MD5
> 3: I1009 14:32:44.645380 17254 authenticatee.cpp:239] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> 3: I1009 14:32:44.645476 17255 authenticator.cpp:204] Received SASL
> authentication start
> 3: I1009 14:32:44.645531 17255 authenticator.cpp:326] Authentication
> requires more steps
> 3: @ 0x2b686835ddea _ZNSt6thread5_ImplISt12_Bind_s
> impleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
> 3: I1009 14:32:44.645639 17241 authenticatee.cpp:259] Received SASL
> authentication step
> 3: @ 0x2b686ac92a60 (unknown)
> 3: I1009 14:32:44.645792 17243 authenticator.cpp:232] Received SASL
> authentication step
> 3: I1009 14:32:44.645825 17243 auxprop.cpp:109] Request to lookup
> properties for user: 'test-principal' realm: '4c36cea139f4' server FQDN:
> '4c36cea139f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> 3: I1009 14:32:44.645843 17243 auxprop.cpp:181] Looking up auxiliary
> property '*userPassword'
> 3: @ 0x2b6868acd184 start_thread
> 3: I1009 14:32:44.645891 17243 auxprop.cpp:181] Looking up auxiliary
> property '*cmusaslsecretCRAM-MD5'
> 3: I1009 14:32:44.645920 17243 auxprop.cpp:109] Request to lookup
> properties for user: 'test-principal' realm: '4c36cea139f4' server FQDN:
> '4c36cea139f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> 3: I1009 14:32:44.645934 17243 auxprop.cpp:131] Skipping auxiliary
> property '*userPassword' since SASL_AUXPROP_AUTHZID == true
> 3: I1009 14:32:44.645944 17243 auxprop.cpp:131] Skipping auxiliary
> property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> 3: I1009 14:32:44.645967 17243 authenticator.cpp:318] Authentication
> success
> 3: I1009 14:32:44.646044 17257 authenticatee.cpp:299] Authentication
> success
> 3: @ 0x2b686b4feffd (unknown)
> 3: I1009 14:32:44.646118 17258 master.cpp:7966] Successfully authenticated
> principal 'test-principal' at scheduler-2f192676-9d98-441e-9
> 81c-e6bbacc57852@172.17.0.2:45196
> 3: I1009 14:32:44.695025 18181 exec.cpp:508] Agent exited, but framework
> has checkpointing enabled. Waiting 15mins to reconnect with agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0
> 3: I1009 14:33:44.792165 18186 executor.cpp:925] Command exited with
> status 0 (pid: 18200)
> 3: E1009 14:33:44.795965 18199 process.cpp:2577] Failed to shutdown socket
> with fd 7, address 172.17.0.2:56170: Transport endpoint is not connected
> 3: I1009 14:33:45.796049 18199 process.cpp:1068] Failed to accept socket:
> future discarded
> 3/3 Test #3: MesosTests .......................***Exception:
> SegFault107.37 sec
>
> 67% tests passed, 1 tests failed out of 3
>
> Total Test time (real) = 126.60 sec
>
> The following tests FAILED:
> 3 - MesosTests (SEGFAULT)
> Errors while running CTest
> make[3]: *** [CMakeFiles/check] Error 8
> make[3]: Leaving directory `/mesos/build'
> make[2]: *** [CMakeFiles/check.dir/all] Error 2
> make[2]: Leaving directory `/mesos/build'
> make[1]: *** [CMakeFiles/check.dir/rule] Error 2
> make[1]: Leaving directory `/mesos/build'
> make: *** [check] Error 2
> + docker rmi mesos-1507557282-9888
> Untagged: mesos-1507557282-9888:latest
> Deleted: sha256:565ae5087c22af01129c5acff1bb1dd9e2e93b6f0ef8285ff1d9c
> 03f8bb6b84f
> Deleted: sha256:5dca4c3cf9a4667ac163a296fe3adee14f95394811a5768c29e53
> a086520f65d
> Deleted: sha256:409425f1957225472ada2507cbdf5cef7d0b9cf6893eb9287aeb6
> 38415dfc62e
> Deleted: sha256:4ada94ed4a96c2f0704eb901c25b60d014d7699e79aee97516bbb
> 432fdfde0fc
> Deleted: sha256:2fce5778c6b3ab5ec2a6124952e9cbab58751d5806efc325ed66e
> f868120dd20
> Deleted: sha256:436634cf6be5deb422a5204f13cd3990db58b9ad5f232be9ec83e
> edaa3d88a03
> Deleted: sha256:7b9951a5b862012a7be46dbfe3cd5d68f1c229c1761ddf50b4843
> 96bc116e062
> Deleted: sha256:d2f985ce49074c58d9fdfbd60d994b7a73c7e28219fb59d0efc12
> ce201347eb3
> Deleted: sha256:c6d63b8056ede0357ca36ef8947cc910b2a1886e576e80902a3e1
> 59cdcdd9406
> Deleted: sha256:fcb519c264702e2b2a2173e622d582ea5fd49cfb907b145f84430
> 4bd58b5d5ea
> Deleted: sha256:be804dd1491bcf5716b7609104aef2546d5f5a6faf30148977561
> 0297720b1a6
> Deleted: sha256:488d4f25bf57bc227bd77bf3f7158fc947db9f598ab85e4380d0c
> dd5c43c4559
> Deleted: sha256:a204748fdfb7d9704fca8aadb55df58c379821ceb0f406e6963ad
> 74d7270a1c3
> Deleted: sha256:4673d17a746f001fe43c8dd327645483aef3afcbbddba301a81bd
> 3bef5634f66
> Deleted: sha256:a791eb15c43dc940630eb8f37c255030d6ca2a475069d7d84fead
> 5d42bb770e4
> Deleted: sha256:dce6c4482c9daa468b12e411d7c481c58a72fa29600c44d01b38b
> 95bfa29f019
> Deleted: sha256:7a19e2b79479a34ca2246885ac34f21fac793c3d27f70569dc67b
> f04af1a6962
> Deleted: sha256:1bda4372762b6fc3edc83fcab0cab81c4c53a2ccfe1e4d01bc371
> dfd0dabc2f1
> Deleted: sha256:61fd156d867b6025d24f4062ad14dc8f66e5126bebf34f4e3c76b
> 1728f1906de
> Deleted: sha256:74ae88f587cc59ed5a9d484b60964f85668a52b2d7f473d498527
> 8fdea3cb100
> Deleted: sha256:0c9f30a7f70beb0a8f64a00a4a744523b07579c9b6f1086a378b2
> 38cb40e90ae
> Deleted: sha256:65ab04f3f7f58414e16c775bbb2e364a9fb0c07a6431b7734dd33
> 590454d4e16
> Deleted: sha256:52468487cbaf94460eb25f629e503c907f913871ed81108f61612
> 84988e482ed
> Deleted: sha256:21b5c5170ecdeca47c303592d3bd992f02b7c7067d31978e8f53b
> bf965ebd06e
> Deleted: sha256:bf5cb5ab8800a91bb7dd86e8cbadb54683fe6ee8dd05c586c0cf8
> 10f142303d5
> Deleted: sha256:a196a2739bac2a2c30de67a7e75c4cc5e82fc0ed5b65afab1f421
> 95c08d851be
> Deleted: sha256:81318b08864b1e54c1c0c25c73c16c426b745db88b71f8c26f520
> d2462ede4a0
> Deleted: sha256:deb9c31e60117002ba401d0c41c35caa122a8d3ca07e46f6e6c7e
> fb853a8872c
> Build step 'Execute shell' marked build as failure
>
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4298
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4298/display/redirect?page=changes>
Changes:
[bbannier] Formatted 'synchronized' like loop instead of function.
------------------------------------------
[...truncated 3.51 MB...]
3: I1009 14:32:44.304908 17239 slave.cpp:944] Successfully attached '/tmp/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_MU8K9N/slaves/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0/frameworks/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000/executors/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d/runs/26a5d7b2-5ad5-44d5-9c14-e574764a51f8' to virtual path '/tmp/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_MU8K9N/slaves/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0/frameworks/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000/executors/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d/runs/26a5d7b2-5ad5-44d5-9c14-e574764a51f8'
3: I1009 14:32:44.308506 17253 containerizer.cpp:1720] 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:45196"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"1"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"\/tmp\/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_MU8K9N\/slaves\/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0\/frameworks\/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000\/executors\/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d\/runs\/26a5d7b2-5ad5-44d5-9c14-e574764a51f8"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"9b9b3770-d6be-4958-bc33-cff10ae0de17-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_RECOVERY_TIMEOUT","type":"VALUE","value":"15mins"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"9b9b3770-d6be-4958-bc33-cff10ae0de17-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave(59)@172.17.0.2:45196"},{"name":"MESOS_SUBSCRIPTION_BACKOFF_MAX","type":"VALUE","value":"2secs"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"\/tmp\/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_MU8K9N\/slaves\/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0\/frameworks\/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000\/executors\/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d\/runs\/26a5d7b2-5ad5-44d5-9c14-e574764a51f8"}]},"task_environment":{},"user":"mesos","working_directory":"\/tmp\/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_MU8K9N\/slaves\/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0\/frameworks\/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000\/executors\/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d\/runs\/26a5d7b2-5ad5-44d5-9c14-e574764a51f8"}" --pipe_read="7" --pipe_write="8" --runtime_directory="/tmp/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_j7yD9F/containers/26a5d7b2-5ad5-44d5-9c14-e574764a51f8" --unshare_namespace_mnt="false"'
3: I1009 14:32:44.310649 17253 launcher.cpp:140] Forked child with pid '18173' for container '26a5d7b2-5ad5-44d5-9c14-e574764a51f8'
3: I1009 14:32:44.310770 17253 containerizer.cpp:1812] Checkpointing container's forked pid 18173 to '/tmp/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_MU8K9N/meta/slaves/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0/frameworks/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000/executors/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d/runs/26a5d7b2-5ad5-44d5-9c14-e574764a51f8/pids/forked.pid'
3: I1009 14:32:44.311444 17253 containerizer.cpp:2751] Transitioning the state of container 26a5d7b2-5ad5-44d5-9c14-e574764a51f8 from PREPARING to ISOLATING
3: I1009 14:32:44.312573 17257 containerizer.cpp:2751] Transitioning the state of container 26a5d7b2-5ad5-44d5-9c14-e574764a51f8 from ISOLATING to FETCHING
3: I1009 14:32:44.312727 17252 fetcher.cpp:377] Starting to fetch URIs for container: 26a5d7b2-5ad5-44d5-9c14-e574764a51f8, directory: /tmp/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_MU8K9N/slaves/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0/frameworks/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000/executors/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d/runs/26a5d7b2-5ad5-44d5-9c14-e574764a51f8
3: I1009 14:32:44.313786 17239 containerizer.cpp:2751] Transitioning the state of container 26a5d7b2-5ad5-44d5-9c14-e574764a51f8 from FETCHING to RUNNING
3: I1009 14:32:44.545111 18187 exec.cpp:162] Version: 1.5.0
3: I1009 14:32:44.556907 17250 slave.cpp:3957] Got registration for executor 'cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d' of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 from executor(1)@172.17.0.2:36164
3: I1009 14:32:44.557358 17250 slave.cpp:4043] Checkpointing executor pid 'executor(1)@172.17.0.2:36164' to '/tmp/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_MU8K9N/meta/slaves/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0/frameworks/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000/executors/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d/runs/26a5d7b2-5ad5-44d5-9c14-e574764a51f8/pids/libprocess.pid'
3: I1009 14:32:44.559736 17259 slave.cpp:2629] Sending queued task 'cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d' to executor 'cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d' of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 at executor(1)@172.17.0.2:36164
3: I1009 14:32:44.561986 18193 exec.cpp:237] Executor registered on agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0
3: I1009 14:32:44.565585 18198 executor.cpp:171] Received SUBSCRIBED event
3: I1009 14:32:44.567385 18198 executor.cpp:175] Subscribed executor on 4c36cea139f4
3: I1009 14:32:44.567728 18198 executor.cpp:171] Received LAUNCH event
3: I1009 14:32:44.567924 18198 executor.cpp:633] Starting task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d
3: I1009 14:32:44.590138 18198 executor.cpp:477] Running '/mesos/build/src/mesos-containerizer launch <POSSIBLY-SENSITIVE-DATA>'
3: I1009 14:32:44.593616 18198 executor.cpp:646] Forked command at 18200
3: I1009 14:32:44.602321 17253 slave.cpp:4411] Handling status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 from executor(1)@172.17.0.2:36164
3: I1009 14:32:44.605000 17261 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.605087 17261 status_update_manager.cpp:500] Creating StatusUpdate stream for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.606317 17261 status_update_manager.cpp:834] Checkpointing UPDATE for status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.606812 17261 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 to the agent
3: I1009 14:32:44.607204 17251 slave.cpp:4892] Forwarding the update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 to master@172.17.0.2:45196
3: I1009 14:32:44.607499 17251 slave.cpp:4786] Status update manager successfully handled status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.607583 17251 slave.cpp:4802] Sending acknowledgement for status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 to executor(1)@172.17.0.2:36164
3: I1009 14:32:44.607857 17248 master.cpp:6993] Status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 from agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4)
3: I1009 14:32:44.607956 17248 master.cpp:7055] Forwarding status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.608155 17248 master.cpp:9157] Updating the state of task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
3: I1009 14:32:44.608521 17258 sched.cpp:1025] Scheduler::statusUpdate took 142398ns
3: I1009 14:32:44.608929 17245 master.cpp:5566] Processing ACKNOWLEDGE call 5903d32e-5574-45f8-91f8-1c385908dbc9 for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 (Framework 1) at scheduler-e2a439e4-2854-437f-a947-f5b320448e5f@172.17.0.2:45196 on agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0
3: I1009 14:32:44.609356 17253 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.609527 17253 status_update_manager.cpp:834] Checkpointing ACK for status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.609925 17253 slave.cpp:3695] Status update manager successfully handled status update acknowledgement (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.610273 17239 master.cpp:1145] Master terminating
3: W1009 14:32:44.610399 17239 master.cpp:9237] Removing task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 on agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4) in non-removable state TASK_RUNNING
3: I1009 14:32:44.610939 17250 hierarchical.cpp:626] Removed agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0
3: I1009 14:32:44.611913 17250 hierarchical.cpp:355] Removed framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.612026 17259 slave.cpp:5024] Got exited event for master@172.17.0.2:45196
3: W1009 14:32:44.612049 17259 slave.cpp:5029] Master disconnected! Waiting for a new master to be elected
3: I1009 14:32:44.615108 17237 sched.cpp:2005] Asked to stop the driver
3: I1009 14:32:44.615205 17251 sched.cpp:1187] Stopping framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.617620 17237 cluster.cpp:162] Creating default 'local' authorizer
3: I1009 14:32:44.620221 17240 master.cpp:445] Master 45991738-966f-4f70-8c5f-f555b5c45469 (4c36cea139f4) started on 172.17.0.2:45196
3: I1009 14:32:44.620241 17240 master.cpp:447] 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/H8OT9V/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/H8OT9V/master" --zk_session_timeout="10secs"
3: I1009 14:32:44.620631 17240 master.cpp:496] Master only allowing authenticated frameworks to register
3: I1009 14:32:44.620643 17240 master.cpp:502] Master only allowing authenticated agents to register
3: I1009 14:32:44.620651 17240 master.cpp:508] Master only allowing authenticated HTTP frameworks to register
3: I1009 14:32:44.620661 17240 credentials.hpp:37] Loading credentials for authentication from '/tmp/H8OT9V/credentials'
3: I1009 14:32:44.621049 17240 master.cpp:552] Using default 'crammd5' authenticator
3: I1009 14:32:44.621263 17240 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1009 14:32:44.621451 17240 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1009 14:32:44.621611 17240 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1009 14:32:44.621762 17240 master.cpp:631] Authorization enabled
3: I1009 14:32:44.621953 17258 whitelist_watcher.cpp:77] No whitelist given
3: I1009 14:32:44.622021 17245 hierarchical.cpp:171] Initialized hierarchical allocator process
3: I1009 14:32:44.625402 17244 master.cpp:2198] Elected as the leading master!
3: I1009 14:32:44.625428 17244 master.cpp:1687] Recovering from registrar
3: I1009 14:32:44.625560 17242 registrar.cpp:347] Recovering registrar
3: I1009 14:32:44.626109 17242 registrar.cpp:391] Successfully fetched the registry (0B) in 515840ns
3: I1009 14:32:44.626219 17242 registrar.cpp:495] Applied 1 operations in 28313ns; attempting to update the registry
3: I1009 14:32:44.626760 17242 registrar.cpp:552] Successfully updated the registry in 489728ns
3: I1009 14:32:44.626868 17242 registrar.cpp:424] Successfully recovered registrar
3: I1009 14:32:44.627198 17261 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I1009 14:32:44.627213 17258 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
3: I1009 14:32:44.627843 17250 status_update_manager.cpp:177] Pausing sending status updates
3: I1009 14:32:44.627868 17238 slave.cpp:993] New master detected at master@172.17.0.2:45196
3: I1009 14:32:44.627955 17238 slave.cpp:1028] Detecting new master
3: I1009 14:32:44.634227 17239 slave.cpp:1055] Authenticating with master master@172.17.0.2:45196
3: I1009 14:32:44.634287 17239 slave.cpp:1064] Using default CRAM-MD5 authenticatee
3: I1009 14:32:44.634569 17244 authenticatee.cpp:121] Creating new client SASL connection
3: I1009 14:32:44.634912 17260 master.cpp:7936] Authenticating slave(59)@172.17.0.2:45196
3: I1009 14:32:44.635037 17259 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(137)@172.17.0.2:45196
3: I1009 14:32:44.635282 17255 authenticator.cpp:98] Creating new server SASL connection
3: I1009 14:32:44.635511 17252 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1009 14:32:44.635537 17252 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1009 14:32:44.635627 17252 authenticator.cpp:204] Received SASL authentication start
3: I1009 14:32:44.635682 17252 authenticator.cpp:326] Authentication requires more steps
3: I1009 14:32:44.635807 17249 authenticatee.cpp:259] Received SASL authentication step
3: I1009 14:32:44.635953 17254 authenticator.cpp:232] Received SASL authentication step
3: I1009 14:32:44.635988 17254 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4c36cea139f4' server FQDN: '4c36cea139f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1009 14:32:44.636006 17254 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1009 14:32:44.636054 17254 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1009 14:32:44.636081 17254 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4c36cea139f4' server FQDN: '4c36cea139f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1009 14:32:44.636096 17254 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1009 14:32:44.636104 17254 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1009 14:32:44.636124 17254 authenticator.cpp:318] Authentication success
3: I1009 14:32:44.636229 17246 authenticatee.cpp:299] Authentication success
3: I1009 14:32:44.636298 17241 master.cpp:7966] Successfully authenticated principal 'test-principal' at slave(59)@172.17.0.2:45196
3: I1009 14:32:44.636328 17242 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(137)@172.17.0.2:45196
3: I1009 14:32:44.636513 17251 slave.cpp:1147] Successfully authenticated with master master@172.17.0.2:45196
3: I1009 14:32:44.637054 17251 slave.cpp:1642] Will retry registration in 1.416192ms if necessary
3: *** Aborted at 1507559564 (unix time) try "date -d @1507559564" if you are using GNU date ***
3: I1009 14:32:44.637579 17248 master.cpp:6119] Received re-register agent message from agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4)
3: I1009 14:32:44.637825 17248 master.cpp:3838] Authorizing agent with principal 'test-principal'
3: I1009 14:32:44.638353 17253 master.cpp:6188] Authorized re-registration of agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4)
3: I1009 14:32:44.638433 17253 master.cpp:6324] Re-registering agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4)
3: W1009 14:32:44.638944 17256 master.hpp:2224] Allowing UNKNOWN agent to reregister: hostname: "4c36cea139f4"
3: resources {
3: name: "cpus"
3: type: SCALAR
3: scalar {
3: value: 2
3: }
3: role: "*"
3: }
3: resources {
3: name: "mem"
3: type: SCALAR
3: scalar {
3: value: 1024
3: }
3: role: "*"
3: }
3: resources {
3: name: "disk"
3: type: SCALAR
3: scalar {
3: value: 1024
3: }
3: role: "*"
3: }
3: resources {
3: name: "ports"
3: type: RANGES
3: ranges {
3: range {
3: begin: 31000
3: end: 32000
3: }
3: }
3: role: "*"
3: }
3: id {
3: value: "9b9b3770-d6be-4958-bc33-cff10ae0de17-S0"
3: }
3: checkpoint: true
3: port: 45196
3: I1009 14:32:44.639232 17256 registrar.cpp:495] Applied 1 operations in 336929ns; attempting to update the registry
3: PC: @ 0x2b686836a104 process::EventQueue::Consumer::empty()
3: *** SIGSEGV (@0x8) received by PID 17237 (TID 0x2b687496e700) from PID 8; stack trace: ***
3: I1009 14:32:44.639811 17250 slave.cpp:1642] Will retry registration in 21.825496ms if necessary
3: @ 0x2b6868ad5330 (unknown)
3: I1009 14:32:44.639966 17256 registrar.cpp:552] Successfully updated the registry in 667136ns
3: I1009 14:32:44.640117 17260 master.cpp:6084] Ignoring re-register agent message from agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4) as re-registration is already in progress
3: I1009 14:32:44.640242 17260 master.cpp:6393] Re-admitted agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4)
3: I1009 14:32:44.640743 17260 master.cpp:10135] Adding task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4)
3: I1009 14:32:44.641433 17255 slave.cpp:4982] Received ping from slave-observer(53)@172.17.0.2:45196
3: I1009 14:32:44.641490 17260 master.cpp:6571] Re-registered agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1009 14:32:44.641749 17260 master.cpp:6654] Recovering framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 from re-registering agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4)
3: I1009 14:32:44.641808 17255 slave.cpp:1313] Re-registered with master master@172.17.0.2:45196
3: I1009 14:32:44.641847 17257 hierarchical.cpp:593] Added agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 (4c36cea139f4) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000])
3: I1009 14:32:44.641917 17249 status_update_manager.cpp:184] Resuming sending status updates
3: I1009 14:32:44.641906 17255 slave.cpp:1351] Forwarding total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1009 14:32:44.641985 17255 slave.cpp:1360] Forwarding total oversubscribed resources {}
3: I1009 14:32:44.642164 17257 hierarchical.cpp:1943] No allocations performed
3: I1009 14:32:44.642222 17257 hierarchical.cpp:1486] Performed allocation for 1 agents in 176354ns
3: @ 0x2b686836a104 process::EventQueue::Consumer::empty()
3: I1009 14:32:44.642460 17260 master.cpp:6694] Sending updated checkpointed resources {} to agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4)
3: I1009 14:32:44.642587 17242 slave.cpp:3462] Ignoring new checkpointed resources identical to the current version: {}
3: W1009 14:32:44.642791 17237 process.cpp:3194] Attempted to spawn already running process version@172.17.0.2:45196
3: I1009 14:32:44.642801 17260 master.cpp:6804] Received update of agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4) with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1009 14:32:44.642875 17260 master.cpp:6817] Received update of agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4) with total oversubscribed resources {}
3: I1009 14:32:44.643102 17260 master.cpp:6828] Ignoring update on agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4) as it reports no changes
3: I1009 14:32:44.643549 17261 hierarchical.cpp:303] Added framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.643568 17237 sched.cpp:232] Version: 1.5.0
3: I1009 14:32:44.643597 17261 hierarchical.cpp:412] Deactivated framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: @ 0x2b686834ffbe process::ProcessManager::resume()
3: I1009 14:32:44.644179 17250 sched.cpp:336] New master detected at master@172.17.0.2:45196
3: I1009 14:32:44.644290 17250 sched.cpp:396] Authenticating with master master@172.17.0.2:45196
3: I1009 14:32:44.644306 17250 sched.cpp:403] Using default CRAM-MD5 authenticatee
3: @ 0x2b686834c6f8 _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
3: I1009 14:32:44.644542 17256 authenticatee.cpp:121] Creating new client SASL connection
3: I1009 14:32:44.644791 17252 master.cpp:7936] Authenticating scheduler-2f192676-9d98-441e-981c-e6bbacc57852@172.17.0.2:45196
3: @ 0x2b686835defa _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
3: I1009 14:32:44.644897 17247 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(138)@172.17.0.2:45196
3: I1009 14:32:44.645148 17249 authenticator.cpp:98] Creating new server SASL connection
3: @ 0x2b686835de51 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
3: I1009 14:32:44.645354 17254 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1009 14:32:44.645380 17254 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1009 14:32:44.645476 17255 authenticator.cpp:204] Received SASL authentication start
3: I1009 14:32:44.645531 17255 authenticator.cpp:326] Authentication requires more steps
3: @ 0x2b686835ddea _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
3: I1009 14:32:44.645639 17241 authenticatee.cpp:259] Received SASL authentication step
3: @ 0x2b686ac92a60 (unknown)
3: I1009 14:32:44.645792 17243 authenticator.cpp:232] Received SASL authentication step
3: I1009 14:32:44.645825 17243 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4c36cea139f4' server FQDN: '4c36cea139f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1009 14:32:44.645843 17243 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: @ 0x2b6868acd184 start_thread
3: I1009 14:32:44.645891 17243 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1009 14:32:44.645920 17243 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4c36cea139f4' server FQDN: '4c36cea139f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1009 14:32:44.645934 17243 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1009 14:32:44.645944 17243 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1009 14:32:44.645967 17243 authenticator.cpp:318] Authentication success
3: I1009 14:32:44.646044 17257 authenticatee.cpp:299] Authentication success
3: @ 0x2b686b4feffd (unknown)
3: I1009 14:32:44.646118 17258 master.cpp:7966] Successfully authenticated principal 'test-principal' at scheduler-2f192676-9d98-441e-981c-e6bbacc57852@172.17.0.2:45196
3: I1009 14:32:44.695025 18181 exec.cpp:508] Agent exited, but framework has checkpointing enabled. Waiting 15mins to reconnect with agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0
3: I1009 14:33:44.792165 18186 executor.cpp:925] Command exited with status 0 (pid: 18200)
3: E1009 14:33:44.795965 18199 process.cpp:2577] Failed to shutdown socket with fd 7, address 172.17.0.2:56170: Transport endpoint is not connected
3: I1009 14:33:45.796049 18199 process.cpp:1068] Failed to accept socket: future discarded
3/3 Test #3: MesosTests .......................***Exception: SegFault107.37 sec
67% tests passed, 1 tests failed out of 3
Total Test time (real) = 126.60 sec
The following tests FAILED:
3 - MesosTests (SEGFAULT)
Errors while running CTest
make[3]: *** [CMakeFiles/check] Error 8
make[3]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[2]: Leaving directory `/mesos/build'
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1507557282-9888
Untagged: mesos-1507557282-9888:latest
Deleted: sha256:565ae5087c22af01129c5acff1bb1dd9e2e93b6f0ef8285ff1d9c03f8bb6b84f
Deleted: sha256:5dca4c3cf9a4667ac163a296fe3adee14f95394811a5768c29e53a086520f65d
Deleted: sha256:409425f1957225472ada2507cbdf5cef7d0b9cf6893eb9287aeb638415dfc62e
Deleted: sha256:4ada94ed4a96c2f0704eb901c25b60d014d7699e79aee97516bbb432fdfde0fc
Deleted: sha256:2fce5778c6b3ab5ec2a6124952e9cbab58751d5806efc325ed66ef868120dd20
Deleted: sha256:436634cf6be5deb422a5204f13cd3990db58b9ad5f232be9ec83eedaa3d88a03
Deleted: sha256:7b9951a5b862012a7be46dbfe3cd5d68f1c229c1761ddf50b484396bc116e062
Deleted: sha256:d2f985ce49074c58d9fdfbd60d994b7a73c7e28219fb59d0efc12ce201347eb3
Deleted: sha256:c6d63b8056ede0357ca36ef8947cc910b2a1886e576e80902a3e159cdcdd9406
Deleted: sha256:fcb519c264702e2b2a2173e622d582ea5fd49cfb907b145f844304bd58b5d5ea
Deleted: sha256:be804dd1491bcf5716b7609104aef2546d5f5a6faf301489775610297720b1a6
Deleted: sha256:488d4f25bf57bc227bd77bf3f7158fc947db9f598ab85e4380d0cdd5c43c4559
Deleted: sha256:a204748fdfb7d9704fca8aadb55df58c379821ceb0f406e6963ad74d7270a1c3
Deleted: sha256:4673d17a746f001fe43c8dd327645483aef3afcbbddba301a81bd3bef5634f66
Deleted: sha256:a791eb15c43dc940630eb8f37c255030d6ca2a475069d7d84fead5d42bb770e4
Deleted: sha256:dce6c4482c9daa468b12e411d7c481c58a72fa29600c44d01b38b95bfa29f019
Deleted: sha256:7a19e2b79479a34ca2246885ac34f21fac793c3d27f70569dc67bf04af1a6962
Deleted: sha256:1bda4372762b6fc3edc83fcab0cab81c4c53a2ccfe1e4d01bc371dfd0dabc2f1
Deleted: sha256:61fd156d867b6025d24f4062ad14dc8f66e5126bebf34f4e3c76b1728f1906de
Deleted: sha256:74ae88f587cc59ed5a9d484b60964f85668a52b2d7f473d4985278fdea3cb100
Deleted: sha256:0c9f30a7f70beb0a8f64a00a4a744523b07579c9b6f1086a378b238cb40e90ae
Deleted: sha256:65ab04f3f7f58414e16c775bbb2e364a9fb0c07a6431b7734dd33590454d4e16
Deleted: sha256:52468487cbaf94460eb25f629e503c907f913871ed81108f6161284988e482ed
Deleted: sha256:21b5c5170ecdeca47c303592d3bd992f02b7c7067d31978e8f53bbf965ebd06e
Deleted: sha256:bf5cb5ab8800a91bb7dd86e8cbadb54683fe6ee8dd05c586c0cf810f142303d5
Deleted: sha256:a196a2739bac2a2c30de67a7e75c4cc5e82fc0ed5b65afab1f42195c08d851be
Deleted: sha256:81318b08864b1e54c1c0c25c73c16c426b745db88b71f8c26f520d2462ede4a0
Deleted: sha256:deb9c31e60117002ba401d0c41c35caa122a8d3ca07e46f6e6c7efb853a8872c
Build step 'Execute shell' marked build as failure
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4297
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4297/display/redirect>
------------------------------------------
[...truncated 30.34 MB...]
3: I1007 05:39:30.879179 17251 registrar.cpp:391] Successfully fetched the registry (0B) in 0ns
3: I1007 05:39:30.879292 17251 registrar.cpp:495] Applied 1 operations in 30320ns; attempting to update the registry
3: I1007 05:39:30.879832 17251 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1007 05:39:30.879946 17251 registrar.cpp:424] Successfully recovered registrar
3: I1007 05:39:30.880270 17243 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I1007 05:39:30.880304 17250 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
3: W1007 05:39:30.885615 17236 process.cpp:3194] Attempted to spawn already running process files@172.17.0.4:34265
3: I1007 05:39:30.886533 17236 containerizer.cpp:292] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1007 05:39:30.887007 17236 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
3: W1007 05:39:30.887122 17236 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1007 05:39:30.887151 17236 provisioner.cpp:255] Using default backend 'copy'
3: I1007 05:39:30.888947 17236 cluster.cpp:448] Creating default 'local' authorizer
3: I1007 05:39:30.891031 17243 slave.cpp:254] Mesos agent started on (825)@172.17.0.4:34265
3: I1007 05:39:30.891046 17243 slave.cpp:255] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_IAOfty/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_BadACLDropCreateAndDestroy_1_IAOfty/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_BadACLDropCreateAndDestroy_1_IAOfty/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_BadACLDropCreateAndDestroy_1_IAOfty/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_BadACLDropCreateAndDestroy_1_IAOfty/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"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_IAOfty" --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_BadACLDropCreateAndDestroy_1_Gkh1jX" --zk_session_timeout="10secs"
3: I1007 05:39:30.891559 17243 credentials.hpp:86] Loading credential for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_IAOfty/credential'
3: W1007 05:39:30.891630 17236 process.cpp:3194] Attempted to spawn already running process version@172.17.0.4:34265
3: I1007 05:39:30.891747 17243 slave.cpp:287] Agent using credential for: test-principal
3: I1007 05:39:30.891764 17243 credentials.hpp:37] Loading credentials for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_IAOfty/http_credentials'
3: I1007 05:39:30.892012 17243 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1007 05:39:30.892196 17243 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1007 05:39:30.892535 17236 sched.cpp:232] Version: 1.5.0
3: I1007 05:39:30.893096 17255 sched.cpp:336] New master detected at master@172.17.0.4:34265
3: I1007 05:39:30.893218 17255 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1007 05:39:30.893231 17255 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.4:34265
3: I1007 05:39:30.893343 17255 sched.cpp:853] Will retry registration in 931.516811ms if necessary
3: I1007 05:39:30.893517 17254 master.cpp:2929] Received SUBSCRIBE call for framework 'default' at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.893597 17254 master.cpp:2263] Authorizing framework principal 'test-principal' to receive offers for roles '{ default-role }'
3: I1007 05:39:30.894064 17240 master.cpp:3009] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1007 05:39:30.894600 17247 sched.cpp:747] Framework registered with 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.894645 17247 sched.cpp:761] Scheduler::registered took 25098ns
3: I1007 05:39:30.894865 17256 hierarchical.cpp:303] Added framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.895045 17256 hierarchical.cpp:1943] No allocations performed
3: I1007 05:39:30.895082 17256 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 05:39:30.895117 17256 hierarchical.cpp:1486] Performed allocation for 0 agents in 114875ns
3: I1007 05:39:30.895467 17243 slave.cpp:585] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1007 05:39:30.895844 17243 slave.cpp:593] Agent attributes: [ ]
3: I1007 05:39:30.895853 17243 slave.cpp:602] Agent hostname: e0d9be46a04b
3: I1007 05:39:30.895973 17251 status_update_manager.cpp:177] Pausing sending status updates
3: I1007 05:39:30.897518 17238 state.cpp:64] Recovering state from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_Gkh1jX/meta'
3: I1007 05:39:30.897922 17246 status_update_manager.cpp:203] Recovering status update manager
3: I1007 05:39:30.898165 17246 containerizer.cpp:648] Recovering containerizer
3: I1007 05:39:30.899941 17242 provisioner.cpp:416] Provisioner recovery complete
3: I1007 05:39:30.900266 17253 slave.cpp:6326] Finished recovery
3: I1007 05:39:30.900852 17253 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1007 05:39:30.901099 17243 status_update_manager.cpp:177] Pausing sending status updates
3: I1007 05:39:30.901123 17253 slave.cpp:993] New master detected at master@172.17.0.4:34265
3: I1007 05:39:30.901206 17253 slave.cpp:1028] Detecting new master
3: I1007 05:39:30.901368 17253 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1007 05:39:30.902956 17257 slave.cpp:1055] Authenticating with master master@172.17.0.4:34265
3: I1007 05:39:30.903049 17257 slave.cpp:1064] Using default CRAM-MD5 authenticatee
3: I1007 05:39:30.903434 17244 authenticatee.cpp:121] Creating new client SASL connection
3: I1007 05:39:30.903846 17239 master.cpp:7936] Authenticating slave(825)@172.17.0.4:34265
3: I1007 05:39:30.903981 17246 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1490)@172.17.0.4:34265
3: I1007 05:39:30.903995 17259 hierarchical.cpp:1943] No allocations performed
3: I1007 05:39:30.904057 17259 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 05:39:30.904100 17259 hierarchical.cpp:1486] Performed allocation for 0 agents in 183630ns
3: I1007 05:39:30.904250 17254 authenticator.cpp:98] Creating new server SASL connection
3: I1007 05:39:30.904501 17237 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1007 05:39:30.904525 17237 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1007 05:39:30.904644 17252 authenticator.cpp:204] Received SASL authentication start
3: I1007 05:39:30.904702 17252 authenticator.cpp:326] Authentication requires more steps
3: I1007 05:39:30.904834 17258 authenticatee.cpp:259] Received SASL authentication step
3: I1007 05:39:30.904971 17247 authenticator.cpp:232] Received SASL authentication step
3: I1007 05:39:30.905009 17247 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e0d9be46a04b' server FQDN: 'e0d9be46a04b' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1007 05:39:30.905023 17247 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1007 05:39:30.905072 17247 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1007 05:39:30.905092 17247 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e0d9be46a04b' server FQDN: 'e0d9be46a04b' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1007 05:39:30.905099 17247 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1007 05:39:30.905103 17247 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1007 05:39:30.905117 17247 authenticator.cpp:318] Authentication success
3: I1007 05:39:30.905215 17240 authenticatee.cpp:299] Authentication success
3: I1007 05:39:30.905325 17256 master.cpp:7966] Successfully authenticated principal 'test-principal' at slave(825)@172.17.0.4:34265
3: I1007 05:39:30.905340 17240 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1490)@172.17.0.4:34265
3: I1007 05:39:30.913071 17250 slave.cpp:1147] Successfully authenticated with master master@172.17.0.4:34265
3: I1007 05:39:30.913480 17250 slave.cpp:1642] Will retry registration in 1.514669ms if necessary
3: I1007 05:39:30.913871 17248 master.cpp:5801] Received register agent message from slave(825)@172.17.0.4:34265 (e0d9be46a04b)
3: I1007 05:39:30.914068 17248 master.cpp:3838] Authorizing agent with principal 'test-principal'
3: I1007 05:39:30.914718 17238 master.cpp:5861] Authorized registration of agent at slave(825)@172.17.0.4:34265 (e0d9be46a04b)
3: I1007 05:39:30.914872 17238 master.cpp:5954] Registering agent at slave(825)@172.17.0.4:34265 (e0d9be46a04b) with id 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0
3: I1007 05:39:30.915405 17241 registrar.cpp:495] Applied 1 operations in 78728ns; attempting to update the registry
3: I1007 05:39:30.916147 17241 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1007 05:39:30.916389 17239 master.cpp:6001] Admitted agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b)
3: I1007 05:39:30.917198 17259 slave.cpp:4982] Received ping from slave-observer(741)@172.17.0.4:34265
3: I1007 05:39:30.917372 17259 slave.cpp:1193] Registered with master master@172.17.0.4:34265; given agent ID 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0
3: I1007 05:39:30.917488 17252 status_update_manager.cpp:184] Resuming sending status updates
3: I1007 05:39:30.917309 17239 master.cpp:6032] Registered agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1007 05:39:30.917863 17259 slave.cpp:1213] Checkpointing SlaveInfo to '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_Gkh1jX/meta/slaves/6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0/slave.info'
3: I1007 05:39:30.917973 17254 hierarchical.cpp:593] Added agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 (e0d9be46a04b) with cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; ports:[31000-32000] (allocated: {})
3: I1007 05:39:30.918321 17259 slave.cpp:1263] Forwarding total resources cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; ports:[31000-32000]
3: I1007 05:39:30.918457 17259 slave.cpp:1272] Forwarding total oversubscribed resources {}
3: I1007 05:39:30.918875 17258 master.cpp:6804] Received update of agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b) with total resources cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; ports:[31000-32000]
3: I1007 05:39:30.918982 17258 master.cpp:6817] Received update of agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b) with total oversubscribed resources {}
3: I1007 05:39:30.919257 17258 master.cpp:6828] Ignoring update on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b) as it reports no changes
3: I1007 05:39:30.919879 17254 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 05:39:30.919915 17254 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.758527ms
3: I1007 05:39:30.920552 17247 master.cpp:7766] Sending 1 offers to framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.921077 17256 sched.cpp:917] Scheduler::resourceOffers took 92944ns
3: I1007 05:39:30.922907 17251 master.cpp:9389] Removing offer 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O0
3: I1007 05:39:30.923061 17251 master.cpp:4196] Processing ACCEPT call for offers: [ 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O0 ] on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b) for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.923238 17251 master.cpp:3758] Authorizing principal 'test-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1007 05:39:30.924319 17251 master.cpp:2348] Dropping CREATE offer operation from framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265: Not authorized to create persistent volumes as 'test-principal'
3: I1007 05:39:30.925495 17248 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; ports:[31000-32000], allocated: {}) on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 from framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.928458 17238 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 05:39:30.928505 17238 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.330209ms
3: I1007 05:39:30.929062 17255 master.cpp:7766] Sending 1 offers to framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.929504 17257 sched.cpp:917] Scheduler::resourceOffers took 77586ns
3: I1007 05:39:30.930164 17249 master.cpp:5207] Processing DECLINE call for offers: [ 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O1 ] for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: W1007 05:39:30.930353 17236 process.cpp:3194] Attempted to spawn already running process version@172.17.0.4:34265
3: I1007 05:39:30.930891 17249 master.cpp:9389] Removing offer 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O1
3: I1007 05:39:30.931012 17249 master.cpp:3439] Processing SUPPRESS call for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.931282 17236 sched.cpp:232] Version: 1.5.0
3: I1007 05:39:30.931443 17241 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; ports:[31000-32000], allocated: {}) on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 from framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.931599 17241 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.931917 17259 sched.cpp:336] New master detected at master@172.17.0.4:34265
3: I1007 05:39:30.932034 17259 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1007 05:39:30.932049 17259 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.4:34265
3: I1007 05:39:30.932153 17259 sched.cpp:853] Will retry registration in 1.661588353secs if necessary
3: I1007 05:39:30.932301 17258 master.cpp:2929] Received SUBSCRIBE call for framework 'creator-framework' at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265
3: I1007 05:39:30.932389 17258 master.cpp:2263] Authorizing framework principal 'creator-principal' to receive offers for roles '{ default-role }'
3: I1007 05:39:30.932775 17256 master.cpp:3009] Subscribing framework creator-framework with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1007 05:39:30.933197 17242 hierarchical.cpp:303] Added framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001
3: I1007 05:39:30.933277 17245 sched.cpp:747] Framework registered with 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001
3: I1007 05:39:30.933315 17245 sched.cpp:761] Scheduler::registered took 16843ns
3: I1007 05:39:30.934505 17242 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 05:39:30.934538 17242 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.232173ms
3: I1007 05:39:30.935104 17240 master.cpp:7766] Sending 1 offers to framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 (creator-framework) at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265
3: I1007 05:39:30.935552 17250 sched.cpp:917] Scheduler::resourceOffers took 85197ns
3: I1007 05:39:30.937198 17251 hierarchical.cpp:1943] No allocations performed
3: I1007 05:39:30.937237 17251 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 05:39:30.937268 17251 hierarchical.cpp:1486] Performed allocation for 1 agents in 149587ns
3: I1007 05:39:30.938868 17253 master.cpp:9389] Removing offer 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O2
3: I1007 05:39:30.938967 17253 master.cpp:4196] Processing ACCEPT call for offers: [ 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O2 ] on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b) for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 (creator-framework) at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265
3: I1007 05:39:30.939096 17253 master.cpp:3758] Authorizing principal 'creator-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: I1007 05:39:30.940444 17253 master.cpp:4639] Applying CREATE operation for volumes [{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}] from framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 (creator-framework) at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265 to agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b)
3: I1007 05:39:30.940946 17253 master.cpp:9335] Sending updated checkpointed resources disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048 to agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b)
3: I1007 05:39:30.942026 17249 slave.cpp:3535] Updated checkpointed resources from {} to disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048
3: I1007 05:39:30.944087 17237 hierarchical.cpp:887] Updated allocation of framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 from cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; ports(allocated: default-role):[31000-32000] to cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048
3: I1007 05:39:30.945271 17237 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048, allocated: {}) on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 from framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001
3: I1007 05:39:30.948175 17239 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 05:39:30.948216 17239 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.310969ms
3: I1007 05:39:30.948884 17259 master.cpp:7766] Sending 1 offers to framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 (creator-framework) at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265
3: I1007 05:39:30.949383 17254 sched.cpp:917] Scheduler::resourceOffers took 91064ns
3: I1007 05:39:30.950088 17247 master.cpp:5207] Processing DECLINE call for offers: [ 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O3 ] for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 (creator-framework) at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265
3: I1007 05:39:30.951086 17247 master.cpp:9389] Removing offer 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O3
3: I1007 05:39:30.951252 17247 master.cpp:3439] Processing SUPPRESS call for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 (creator-framework) at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265
3: I1007 05:39:30.951776 17260 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048, allocated: {}) on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 from framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001
3: I1007 05:39:30.951932 17260 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001
3: I1007 05:39:30.952239 17245 master.cpp:5312] Processing REVIVE call for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.952392 17245 hierarchical.cpp:1308] Revived offers for roles { default-role } of framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.954767 17245 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 05:39:30.954823 17245 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.306154ms
3: I1007 05:39:30.955931 17245 master.cpp:7766] Sending 1 offers to framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.956485 17250 sched.cpp:917] Scheduler::resourceOffers took 88962ns
3: I1007 05:39:30.958966 17251 master.cpp:9389] Removing offer 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O4
3: I1007 05:39:30.959128 17251 master.cpp:4196] Processing ACCEPT call for offers: [ 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O4 ] on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b) for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.959345 17251 master.cpp:3810] Authorizing principal 'test-principal' to destroy volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1007 05:39:30.960695 17251 master.cpp:2348] Dropping DESTROY offer operation from framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265: Not authorized to destroy persistent volumes as 'test-principal'
3: I1007 05:39:30.962738 17246 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048, allocated: {}) on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 from framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.965703 17249 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 05:39:30.965745 17249 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.282279ms
3: I1007 05:39:30.966455 17237 master.cpp:7766] Sending 1 offers to framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.966961 17241 sched.cpp:917] Scheduler::resourceOffers took 83451ns
3: I1007 05:39:30.967247 17236 sched.cpp:2005] Asked to stop the driver
3: I1007 05:39:30.967351 17252 sched.cpp:1187] Stopping framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.967630 17239 master.cpp:8447] Processing TEARDOWN call for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.967655 17239 master.cpp:8459] Removing framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.967664 17239 master.cpp:3299] Deactivating framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.967723 17236 sched.cpp:2005] Asked to stop the driver
3: I1007 05:39:30.967777 17258 hierarchical.cpp:412] Deactivated framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.967818 17247 sched.cpp:1187] Stopping framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001
3: I1007 05:39:30.968432 17239 master.cpp:9389] Removing offer 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O5
3: I1007 05:39:30.968622 17243 slave.cpp:3227] Asked to shut down framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 by master@172.17.0.4:34265
3: I1007 05:39:30.968649 17243 slave.cpp:3242] Cannot shut down unknown framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.968914 17239 master.cpp:8447] Processing TEARDOWN call for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 (creator-framework) at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265
3: I1007 05:39:30.968935 17239 master.cpp:8459] Removing framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 (creator-framework) at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265
3: I1007 05:39:30.968943 17239 master.cpp:3299] Deactivating framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 (creator-framework) at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265
3: I1007 05:39:30.969090 17246 slave.cpp:3227] Asked to shut down framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 by master@172.17.0.4:34265
3: I1007 05:39:30.969116 17246 slave.cpp:3242] Cannot shut down unknown framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001
3: I1007 05:39:30.969161 17260 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048, allocated: {}) on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 from framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.969384 17260 hierarchical.cpp:355] Removed framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.969447 17260 hierarchical.cpp:412] Deactivated framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001
3: I1007 05:39:30.969661 17260 hierarchical.cpp:355] Removed framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001
3: I1007 05:39:30.969821 17236 slave.cpp:869] Agent terminating
3: I1007 05:39:30.969996 17258 master.cpp:1303] Agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b) disconnected
3: I1007 05:39:30.970016 17258 master.cpp:3336] Disconnecting agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b)
3: I1007 05:39:30.970072 17258 master.cpp:3355] Deactivating agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b)
3: I1007 05:39:30.970203 17250 hierarchical.cpp:690] Agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 deactivated
3: I1007 05:39:30.975443 17236 master.cpp:1145] Master terminating
3: I1007 05:39:30.976661 17251 hierarchical.cpp:626] Removed agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0
3: [ OK ] DiskResource/PersistentVolumeTest.BadACLDropCreateAndDestroy/1 (113 ms)
3: [----------] 34 tests from DiskResource/PersistentVolumeTest (11234 ms total)
3:
3: [----------] Global test environment tear-down
3: [==========] 1641 tests from 178 test cases ran. (474139 ms total)
3: [ PASSED ] 1640 tests.
3: [ FAILED ] 1 test, listed below:
3: [ FAILED ] RoleTest.ImplicitRoleRegister
3:
3: 1 FAILED TEST
3: YOU HAVE 21 DISABLED TESTS
3:
3: I1007 05:39:31.041824 17261 process.cpp:1068] Failed to accept socket: future discarded
3/3 Test #3: MesosTests .......................***Failed 478.48 sec
67% tests passed, 1 tests failed out of 3
Total Test time (real) = 497.82 sec
The following tests FAILED:
3 - MesosTests (Failed)
Errors while running CTest
make[3]: *** [CMakeFiles/check] Error 8
make[3]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[2]: Leaving directory `/mesos/build'
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1507352357-10058
Untagged: mesos-1507352357-10058:latest
Deleted: sha256:0c39fb129c9a0822a7e1ea5bda71661c6c131260f003aa423fe42fb1cf97d5bc
Deleted: sha256:e3c79dd670513b09dd91ad34ed5f349543822f6e6cd174d444c9043d499481ac
Deleted: sha256:a8e4f0b64e08701b57b85b333e36a47c31dcd1a5112218bb7531f8cea5f94403
Deleted: sha256:6b67be9e443e50cbdc040fe737ba8b598f64bca0f3f8076edc92415f4d7e090e
Deleted: sha256:bb13fa0a0f0d1493b3cca484ac9ddb42514b7204f16fdbef42cf43101b0a56af
Deleted: sha256:0d3fca916dc73541d9afe3b02474ad99069df4d85a0ce5ff4ae67d848e0e430c
Deleted: sha256:3b68915a1e63b191dbc907b6ac28f778b97017bf12bf82631a5df04a53f05178
Deleted: sha256:3b2032d7442f25fe403a9ede3854984310d44b077bbe24042e9fa6b00583485a
Deleted: sha256:77c3b69e0b023b0c22b1fb72af2cd65b4540a282f47f081a0cd5b26aae3b9c2e
Deleted: sha256:6222d1fd8060bee945e762eba404038c03a59ee551a238af9a4c5661569aa3dd
Deleted: sha256:20bf2388496ea688f8747605ef8b0813230381e103e1cc12e2992a072cc37a5b
Deleted: sha256:28d1bbcebe0f9bbcfb8613e62948eaf41e81ce98a8c0f49f4d0a5ad0e7ebb98f
Deleted: sha256:87939cdfa6a0d064fd1b582aa2c9505221894f2e52120cad7aec1325d8ac3d1f
Deleted: sha256:8bbcac2a8dcb08da1f96bd9d7d48d1485d9186b2f06ab879723d84751314a293
Deleted: sha256:92acad7cba2d85bed2e5b7ba23295fd3949ff972d4590ce909950a98152fb388
Deleted: sha256:e3257f5cbae81cfc66d8a98d57308f9690e003d83eb2da8cbf59842dfbc3635b
Deleted: sha256:3e049914f621dfd0e390a56ec25f72f950c740d4e78e42123790075800dd120f
Deleted: sha256:6541c42b10eaee91c07c90b0d5f6abf38796f18bcf4f19f3831ecfa33dc02ba4
Deleted: sha256:e83f1e71c6f0c2c4782210165f0566f1575a5f225dc6412364899dd3ee18391d
Deleted: sha256:ff748d448fa64215816d596d9d46ccc7374b79e0977c9bfb1e9bbfa4638d29bd
Deleted: sha256:270ed78964c27e4208d0b7d0bc959b8deb499b47daf6ee41f72200c19df371aa
Deleted: sha256:e3d7a70ce09ff4e17b4095914f5036265d8f6dcd1761fd61f1c99e07745dabca
Deleted: sha256:68054892f83087f6d0c8b7a4be0966dd9dc48f79acbf57343e2ea5fe43501951
Deleted: sha256:52fc11751d3a242cbb27691c7600156b1d1e76609fd93ae76b69a4cac0bd2dbc
Deleted: sha256:d00e3f1649cc07272d1d1cd1678940be23742af6e4a54a755640452f1e7f3f84
Deleted: sha256:4a202261f07eadabb0cb66f5fbd8458df038fbea69dd75d05953a336d84127fb
Deleted: sha256:c2729a3df3b0c76f58edf1ea3e194264116650e672fe30c8e80b3c0725109fd9
Deleted: sha256:8649c9d45130b93f55629cc1dc8f1c2e34612ebca85f5d7168a592296815fa5e
Build step 'Execute shell' marked build as failure
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4296
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4296/display/redirect?page=changes>
Changes:
[vinodkone] Added `--disable-libtool-wrappers` to support scripts.
[bmahler] Mesos UI: extract the agent URL generation to a function.
------------------------------------------
[...truncated 30.40 MB...]
3: I1007 01:08:51.609475 17109 registrar.cpp:391] Successfully fetched the registry (0B) in 0ns
3: I1007 01:08:51.609678 17109 registrar.cpp:495] Applied 1 operations in 34837ns; attempting to update the registry
3: I1007 01:08:51.610590 17109 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1007 01:08:51.610810 17109 registrar.cpp:424] Successfully recovered registrar
3: I1007 01:08:51.611654 17115 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
3: I1007 01:08:51.611634 17111 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: W1007 01:08:51.621006 17108 process.cpp:3194] Attempted to spawn already running process files@172.17.0.2:45531
3: I1007 01:08:51.622486 17108 containerizer.cpp:292] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1007 01:08:51.623188 17108 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges
3: W1007 01:08:51.623402 17108 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1007 01:08:51.623442 17108 provisioner.cpp:255] Using default backend 'copy'
3: I1007 01:08:51.626081 17108 cluster.cpp:448] Creating default 'local' authorizer
3: I1007 01:08:51.629431 17111 slave.cpp:254] Mesos agent started on (825)@172.17.0.2:45531
3: W1007 01:08:51.630379 17108 process.cpp:3194] Attempted to spawn already running process version@172.17.0.2:45531
3: I1007 01:08:51.629459 17111 slave.cpp:255] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vgII9q/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_BadACLDropCreateAndDestroy_1_vgII9q/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_BadACLDropCreateAndDestroy_1_vgII9q/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_BadACLDropCreateAndDestroy_1_vgII9q/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_BadACLDropCreateAndDestroy_1_vgII9q/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"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vgII9q" --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_BadACLDropCreateAndDestroy_1_1SOHhZ" --zk_session_timeout="10secs"
3: I1007 01:08:51.630472 17111 credentials.hpp:86] Loading credential for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vgII9q/credential'
3: I1007 01:08:51.631032 17111 slave.cpp:287] Agent using credential for: test-principal
3: I1007 01:08:51.631063 17111 credentials.hpp:37] Loading credentials for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vgII9q/http_credentials'
3: I1007 01:08:51.631716 17111 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1007 01:08:51.631875 17108 sched.cpp:232] Version: 1.5.0
3: I1007 01:08:51.632505 17111 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1007 01:08:51.633200 17112 sched.cpp:336] New master detected at master@172.17.0.2:45531
3: I1007 01:08:51.633427 17112 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1007 01:08:51.633447 17112 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.2:45531
3: I1007 01:08:51.633633 17112 sched.cpp:853] Will retry registration in 932.167629ms if necessary
3: I1007 01:08:51.633929 17113 master.cpp:2929] Received SUBSCRIBE call for framework 'default' at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.634076 17113 master.cpp:2263] Authorizing framework principal 'test-principal' to receive offers for roles '{ default-role }'
3: I1007 01:08:51.634811 17115 master.cpp:3009] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1007 01:08:51.635710 17112 sched.cpp:747] Framework registered with dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.635799 17112 sched.cpp:761] Scheduler::registered took 55770ns
3: I1007 01:08:51.635907 17110 hierarchical.cpp:303] Added framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.636157 17110 hierarchical.cpp:1943] No allocations performed
3: I1007 01:08:51.636232 17110 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 01:08:51.636307 17110 hierarchical.cpp:1486] Performed allocation for 0 agents in 196273ns
3: I1007 01:08:51.640771 17111 slave.cpp:585] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1007 01:08:51.641556 17111 slave.cpp:593] Agent attributes: [ ]
3: I1007 01:08:51.641571 17111 slave.cpp:602] Agent hostname: 42eec82fd855
3: I1007 01:08:51.641803 17113 status_update_manager.cpp:177] Pausing sending status updates
3: I1007 01:08:51.644362 17114 state.cpp:64] Recovering state from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_1SOHhZ/meta'
3: I1007 01:08:51.644930 17110 status_update_manager.cpp:203] Recovering status update manager
3: I1007 01:08:51.645267 17116 containerizer.cpp:648] Recovering containerizer
3: I1007 01:08:51.647770 17115 provisioner.cpp:416] Provisioner recovery complete
3: I1007 01:08:51.648578 17116 slave.cpp:6326] Finished recovery
3: I1007 01:08:51.649405 17116 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1007 01:08:51.649914 17113 status_update_manager.cpp:177] Pausing sending status updates
3: I1007 01:08:51.649920 17116 slave.cpp:993] New master detected at master@172.17.0.2:45531
3: I1007 01:08:51.650297 17116 slave.cpp:1028] Detecting new master
3: I1007 01:08:51.650738 17116 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1007 01:08:51.652564 17111 slave.cpp:1055] Authenticating with master master@172.17.0.2:45531
3: I1007 01:08:51.652695 17111 slave.cpp:1064] Using default CRAM-MD5 authenticatee
3: I1007 01:08:51.653167 17110 hierarchical.cpp:1943] No allocations performed
3: I1007 01:08:51.653261 17110 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 01:08:51.653342 17110 hierarchical.cpp:1486] Performed allocation for 0 agents in 238880ns
3: I1007 01:08:51.653542 17109 authenticatee.cpp:121] Creating new client SASL connection
3: I1007 01:08:51.654086 17112 master.cpp:7936] Authenticating slave(825)@172.17.0.2:45531
3: I1007 01:08:51.654356 17116 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1495)@172.17.0.2:45531
3: I1007 01:08:51.654974 17115 authenticator.cpp:98] Creating new server SASL connection
3: I1007 01:08:51.655372 17114 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1007 01:08:51.655412 17114 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1007 01:08:51.655679 17113 authenticator.cpp:204] Received SASL authentication start
3: I1007 01:08:51.655797 17113 authenticator.cpp:326] Authentication requires more steps
3: I1007 01:08:51.655971 17113 authenticatee.cpp:259] Received SASL authentication step
3: I1007 01:08:51.656121 17113 authenticator.cpp:232] Received SASL authentication step
3: I1007 01:08:51.656193 17113 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '42eec82fd855' server FQDN: '42eec82fd855' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1007 01:08:51.656250 17113 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1007 01:08:51.656334 17113 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1007 01:08:51.656399 17113 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '42eec82fd855' server FQDN: '42eec82fd855' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1007 01:08:51.656453 17113 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1007 01:08:51.656504 17113 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1007 01:08:51.656565 17113 authenticator.cpp:318] Authentication success
3: I1007 01:08:51.656811 17109 authenticatee.cpp:299] Authentication success
3: I1007 01:08:51.656821 17112 master.cpp:7966] Successfully authenticated principal 'test-principal' at slave(825)@172.17.0.2:45531
3: I1007 01:08:51.657066 17116 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1495)@172.17.0.2:45531
3: I1007 01:08:51.657331 17115 slave.cpp:1147] Successfully authenticated with master master@172.17.0.2:45531
3: I1007 01:08:51.657841 17115 slave.cpp:1642] Will retry registration in 7.751548ms if necessary
3: I1007 01:08:51.658435 17116 master.cpp:5801] Received register agent message from slave(825)@172.17.0.2:45531 (42eec82fd855)
3: I1007 01:08:51.658798 17116 master.cpp:3838] Authorizing agent with principal 'test-principal'
3: I1007 01:08:51.659605 17114 master.cpp:5861] Authorized registration of agent at slave(825)@172.17.0.2:45531 (42eec82fd855)
3: I1007 01:08:51.659886 17114 master.cpp:5954] Registering agent at slave(825)@172.17.0.2:45531 (42eec82fd855) with id dcf9063e-e533-41d0-b6a2-9e738551e92d-S0
3: I1007 01:08:51.660749 17110 registrar.cpp:495] Applied 1 operations in 118728ns; attempting to update the registry
3: I1007 01:08:51.661968 17116 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1007 01:08:51.662528 17109 master.cpp:6001] Admitted agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855)
3: I1007 01:08:51.663799 17110 slave.cpp:4982] Received ping from slave-observer(742)@172.17.0.2:45531
3: I1007 01:08:51.664001 17109 master.cpp:6032] Registered agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1007 01:08:51.665336 17110 slave.cpp:1193] Registered with master master@172.17.0.2:45531; given agent ID dcf9063e-e533-41d0-b6a2-9e738551e92d-S0
3: I1007 01:08:51.665747 17111 status_update_manager.cpp:184] Resuming sending status updates
3: I1007 01:08:51.664919 17113 hierarchical.cpp:593] Added agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 (42eec82fd855) with cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; ports:[31000-32000] (allocated: {})
3: I1007 01:08:51.690585 17113 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 01:08:51.690650 17113 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.655577ms
3: I1007 01:08:51.690758 17110 slave.cpp:1213] Checkpointing SlaveInfo to '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_1SOHhZ/meta/slaves/dcf9063e-e533-41d0-b6a2-9e738551e92d-S0/slave.info'
3: I1007 01:08:51.691371 17110 slave.cpp:1263] Forwarding total resources cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; ports:[31000-32000]
3: I1007 01:08:51.691555 17110 slave.cpp:1272] Forwarding total oversubscribed resources {}
3: I1007 01:08:51.691936 17115 master.cpp:7766] Sending 1 offers to framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.692682 17115 master.cpp:6804] Received update of agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855) with total resources cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; ports:[31000-32000]
3: I1007 01:08:51.692829 17115 master.cpp:6817] Received update of agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855) with total oversubscribed resources {}
3: I1007 01:08:51.692927 17116 sched.cpp:917] Scheduler::resourceOffers took 142879ns
3: I1007 01:08:51.693186 17115 master.cpp:6828] Ignoring update on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855) as it reports no changes
3: I1007 01:08:51.696000 17109 master.cpp:9389] Removing offer dcf9063e-e533-41d0-b6a2-9e738551e92d-O0
3: I1007 01:08:51.696159 17109 master.cpp:4196] Processing ACCEPT call for offers: [ dcf9063e-e533-41d0-b6a2-9e738551e92d-O0 ] on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855) for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.696400 17109 master.cpp:3758] Authorizing principal 'test-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1007 01:08:51.698062 17116 master.cpp:2348] Dropping CREATE offer operation from framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531: Not authorized to create persistent volumes as 'test-principal'
3: I1007 01:08:51.701030 17116 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; ports:[31000-32000], allocated: {}) on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 from framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.705638 17109 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 01:08:51.705715 17109 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.595587ms
3: I1007 01:08:51.707201 17111 master.cpp:7766] Sending 1 offers to framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.708338 17115 sched.cpp:917] Scheduler::resourceOffers took 133485ns
3: W1007 01:08:51.709475 17108 process.cpp:3194] Attempted to spawn already running process version@172.17.0.2:45531
3: I1007 01:08:51.709563 17113 master.cpp:5207] Processing DECLINE call for offers: [ dcf9063e-e533-41d0-b6a2-9e738551e92d-O1 ] for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.710647 17108 sched.cpp:232] Version: 1.5.0
3: I1007 01:08:51.710983 17113 master.cpp:9389] Removing offer dcf9063e-e533-41d0-b6a2-9e738551e92d-O1
3: I1007 01:08:51.711202 17113 master.cpp:3439] Processing SUPPRESS call for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.711372 17110 sched.cpp:336] New master detected at master@172.17.0.2:45531
3: I1007 01:08:51.711513 17110 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1007 01:08:51.711527 17110 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.2:45531
3: I1007 01:08:51.711658 17110 sched.cpp:853] Will retry registration in 1.912110513secs if necessary
3: I1007 01:08:51.712036 17114 master.cpp:2929] Received SUBSCRIBE call for framework 'creator-framework' at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531
3: I1007 01:08:51.712329 17114 master.cpp:2263] Authorizing framework principal 'creator-principal' to receive offers for roles '{ default-role }'
3: I1007 01:08:51.713147 17113 master.cpp:3009] Subscribing framework creator-framework with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1007 01:08:51.713912 17116 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; ports:[31000-32000], allocated: {}) on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 from framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.714063 17109 sched.cpp:747] Framework registered with dcf9063e-e533-41d0-b6a2-9e738551e92d-0001
3: I1007 01:08:51.714128 17109 sched.cpp:761] Scheduler::registered took 29484ns
3: I1007 01:08:51.714344 17116 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.714668 17116 hierarchical.cpp:303] Added framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001
3: I1007 01:08:51.717568 17116 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 01:08:51.717639 17116 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.67258ms
3: I1007 01:08:51.718847 17110 master.cpp:7766] Sending 1 offers to framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 (creator-framework) at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531
3: I1007 01:08:51.719970 17112 sched.cpp:917] Scheduler::resourceOffers took 148283ns
3: I1007 01:08:51.722179 17111 hierarchical.cpp:1943] No allocations performed
3: I1007 01:08:51.722239 17111 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 01:08:51.722282 17111 hierarchical.cpp:1486] Performed allocation for 1 agents in 303812ns
3: I1007 01:08:51.725571 17115 master.cpp:9389] Removing offer dcf9063e-e533-41d0-b6a2-9e738551e92d-O2
3: I1007 01:08:51.725733 17115 master.cpp:4196] Processing ACCEPT call for offers: [ dcf9063e-e533-41d0-b6a2-9e738551e92d-O2 ] on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855) for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 (creator-framework) at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531
3: I1007 01:08:51.726083 17115 master.cpp:3758] Authorizing principal 'creator-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: I1007 01:08:51.728639 17114 master.cpp:4639] Applying CREATE operation for volumes [{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}] from framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 (creator-framework) at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531 to agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855)
3: I1007 01:08:51.729810 17114 master.cpp:9335] Sending updated checkpointed resources disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048 to agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855)
3: I1007 01:08:51.731711 17113 slave.cpp:3535] Updated checkpointed resources from {} to disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048
3: I1007 01:08:51.737141 17109 hierarchical.cpp:887] Updated allocation of framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 from cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; ports(allocated: default-role):[31000-32000] to cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048
3: I1007 01:08:51.740005 17109 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048, allocated: {}) on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 from framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001
3: I1007 01:08:51.744724 17116 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 01:08:51.744803 17116 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.69231ms
3: I1007 01:08:51.746376 17112 master.cpp:7766] Sending 1 offers to framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 (creator-framework) at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531
3: I1007 01:08:51.747540 17111 sched.cpp:917] Scheduler::resourceOffers took 144232ns
3: I1007 01:08:51.748836 17113 master.cpp:5207] Processing DECLINE call for offers: [ dcf9063e-e533-41d0-b6a2-9e738551e92d-O3 ] for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 (creator-framework) at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531
3: I1007 01:08:51.750329 17113 master.cpp:9389] Removing offer dcf9063e-e533-41d0-b6a2-9e738551e92d-O3
3: I1007 01:08:51.750556 17113 master.cpp:3439] Processing SUPPRESS call for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 (creator-framework) at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531
3: I1007 01:08:51.751869 17109 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048, allocated: {}) on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 from framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001
3: I1007 01:08:51.752292 17109 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001
3: I1007 01:08:51.752790 17110 master.cpp:5312] Processing REVIVE call for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.753160 17116 hierarchical.cpp:1308] Revived offers for roles { default-role } of framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.756053 17116 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 01:08:51.756188 17116 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.669864ms
3: I1007 01:08:51.757594 17112 master.cpp:7766] Sending 1 offers to framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.758877 17111 sched.cpp:917] Scheduler::resourceOffers took 147791ns
3: I1007 01:08:51.761690 17113 master.cpp:9389] Removing offer dcf9063e-e533-41d0-b6a2-9e738551e92d-O4
3: I1007 01:08:51.761860 17113 master.cpp:4196] Processing ACCEPT call for offers: [ dcf9063e-e533-41d0-b6a2-9e738551e92d-O4 ] on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855) for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.762087 17113 master.cpp:3810] Authorizing principal 'test-principal' to destroy volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1007 01:08:51.764084 17112 master.cpp:2348] Dropping DESTROY offer operation from framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531: Not authorized to destroy persistent volumes as 'test-principal'
3: I1007 01:08:51.766876 17114 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048, allocated: {}) on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 from framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.771569 17115 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 01:08:51.771646 17115 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.646444ms
3: I1007 01:08:51.772902 17110 master.cpp:7766] Sending 1 offers to framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.773792 17110 sched.cpp:917] Scheduler::resourceOffers took 136212ns
3: I1007 01:08:51.774350 17108 sched.cpp:2005] Asked to stop the driver
3: I1007 01:08:51.774634 17116 sched.cpp:1187] Stopping framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.775117 17108 sched.cpp:2005] Asked to stop the driver
3: I1007 01:08:51.775205 17114 sched.cpp:1187] Stopping framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001
3: I1007 01:08:51.775225 17111 master.cpp:8447] Processing TEARDOWN call for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.775671 17111 master.cpp:8459] Removing framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.775692 17111 master.cpp:3299] Deactivating framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.775882 17113 hierarchical.cpp:412] Deactivated framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.777647 17111 master.cpp:9389] Removing offer dcf9063e-e533-41d0-b6a2-9e738551e92d-O5
3: I1007 01:08:51.778033 17112 slave.cpp:3227] Asked to shut down framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 by master@172.17.0.2:45531
3: I1007 01:08:51.778082 17112 slave.cpp:3242] Cannot shut down unknown framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.778862 17111 master.cpp:8447] Processing TEARDOWN call for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 (creator-framework) at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531
3: I1007 01:08:51.778911 17111 master.cpp:8459] Removing framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 (creator-framework) at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531
3: I1007 01:08:51.778931 17111 master.cpp:3299] Deactivating framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 (creator-framework) at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531
3: I1007 01:08:51.779594 17110 slave.cpp:3227] Asked to shut down framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 by master@172.17.0.2:45531
3: I1007 01:08:51.779641 17110 slave.cpp:3242] Cannot shut down unknown framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001
3: I1007 01:08:51.780040 17116 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048, allocated: {}) on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 from framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.780542 17116 hierarchical.cpp:355] Removed framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.780709 17116 hierarchical.cpp:412] Deactivated framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001
3: I1007 01:08:51.781384 17116 hierarchical.cpp:355] Removed framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001
3: I1007 01:08:51.781785 17108 slave.cpp:869] Agent terminating
3: I1007 01:08:51.782121 17109 master.cpp:1303] Agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855) disconnected
3: I1007 01:08:51.782163 17109 master.cpp:3336] Disconnecting agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855)
3: I1007 01:08:51.782299 17109 master.cpp:3355] Deactivating agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855)
3: I1007 01:08:51.782578 17115 hierarchical.cpp:690] Agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 deactivated
3: I1007 01:08:51.789908 17108 master.cpp:1145] Master terminating
3: I1007 01:08:51.791815 17111 hierarchical.cpp:626] Removed agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0
3: [ OK ] DiskResource/PersistentVolumeTest.BadACLDropCreateAndDestroy/1 (206 ms)
3: [----------] 34 tests from DiskResource/PersistentVolumeTest (15350 ms total)
3:
3: [----------] Global test environment tear-down
3: [==========] 1641 tests from 178 test cases ran. (430319 ms total)
3: [ PASSED ] 1640 tests.
3: [ FAILED ] 1 test, listed below:
3: [ FAILED ] ContentType/MasterAPITest.ReserveResources/1, where GetParam() = application/json
3:
3: 1 FAILED TEST
3: YOU HAVE 21 DISABLED TESTS
3:
3: I1007 01:08:51.935554 17117 process.cpp:1068] Failed to accept socket: future discarded
3/3 Test #3: MesosTests .......................***Failed 443.82 sec
67% tests passed, 1 tests failed out of 3
Total Test time (real) = 476.87 sec
The following tests FAILED:
3 - MesosTests (Failed)
Errors while running CTest
make[3]: *** [CMakeFiles/check] Error 8
make[3]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[2]: Leaving directory `/mesos/build'
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1507334412-28315
Untagged: mesos-1507334412-28315:latest
Deleted: sha256:58c35d648e0ebd5c069f57531b393f76fa71e0a0ff13a4fb15bb3ad29ef214cb
Deleted: sha256:b46fb5cb4af37ad22ef40c441cf029d6256f9d6a55d2ff6908e875a8d5ed8f29
Deleted: sha256:0cf7db2961cc152fe403fcfdd99bd5a3a319d9edfabbbbbca7e7ddbed566c3fd
Deleted: sha256:856180a6d461e25f154d6f3da2366326bf15b644744f6d26a7adc7b68e69e524
Deleted: sha256:85abe7dcdfbf01ba24fe50247feeae48f938cd9175a1bae8edbfac9dcf38f923
Deleted: sha256:799109489e98af2983360fbca471fb4d54de57ba28d03f4697cb969c84c7eabf
Deleted: sha256:27a8dc0007e30b762ffb3e2f82cdf958eb22c6bc4ca7d93a25859d3eedfabb78
Deleted: sha256:cfbf3321b2ad4da1338caacfa5d76b7e6ae01d707893e1376baf57ee1cf38110
Deleted: sha256:3e73dcceaa6c484b7f4835f25e81dfa485375e875dd95236d37d29a3794b66be
Deleted: sha256:986632f5c370371f614d78173108bb4525e323153f3e1045fc0984e55669418f
Deleted: sha256:4f688b22be9de9b44469c94fc2f3053e4163c38bf06c44efcb8ec4457ea43410
Deleted: sha256:3ef64fe475003bf6dabef7d4e3ea2e35c4c994ce40258be99e1dd1b01ae0a320
Deleted: sha256:4c261ddb5c8abb90cd6b9a521510428b9d99d02d52c9e38ca5a1fcf1812dc107
Deleted: sha256:e005f1b1bf95fa55c59a0852ba9a089f42a520c6bf9b6fcd5658c28f38f73af5
Deleted: sha256:5c648d5c18b7d494e1aded891387bd74c49f1472176bd734569349b842d7f626
Deleted: sha256:69d626e135eefcf31925cc8fe391dda50d388979870522919ff8bedced9b3d13
Deleted: sha256:2f3381f5430aa018d17c6c2c5b891f94fef6d23b643a518f9ba26d53f2a5f740
Deleted: sha256:a9a96427afb0f44b968b546ee156863137aef9d6ea00754aa68ef0e361d38f5b
Deleted: sha256:2b7351f0e46c4eaa48b9a4b3e2462a7d77b66fc3c52a0a5ddae3740caaab357c
Deleted: sha256:1221c2eb9e744ecacf55b2bf6e13585c0e375fda28c266accac210a88ea3d377
Deleted: sha256:0b1dfd611834f7005cc0c0f7271c059964745e8456a854c7da0ebe63682c14ee
Deleted: sha256:60b982d37f8842938270f9540132e799b3240da27bfa7763de7499047a40f32a
Deleted: sha256:f08b29229845203bc3c0a12638de2a93f18fbbd96b8169176bf191602b833943
Deleted: sha256:084f28ace74c10578c91d0aaed30f41ba06599831d0f585a4122b82c16c86fec
Deleted: sha256:6417feae46b0a69a6125ef1b1ed05ca90f8b63ff7ec69f4042603e3e522e7666
Deleted: sha256:3fe00ea383f66565f1a6c36b3889d56f5cc537c074d3bdf9947e3c215d8da92e
Deleted: sha256:46918bac56a654863c70d13c6a75b58f251ba5e74c344bbefa1db8ee609f490d
Deleted: sha256:c5a3e660baaaee85c3444ea7df060c64dcc0ebf599aa778db016e019b2e6d758
Build step 'Execute shell' marked build as failure