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 23:52:49 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4296
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,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>
------------------------------------------
Failed to access build log
java.lang.OutOfMemoryError: GC overhead limit exceeded
at java.lang.Class.getDeclaredFields0(Native Method)
at java.lang.Class.privateGetDeclaredFields(Class.java:2583)
at java.lang.Class.getDeclaredField(Class.java:2068)
at java.io.ObjectStreamClass.getDeclaredSUID(ObjectStreamClass.java:1703)
at java.io.ObjectStreamClass.access$700(ObjectStreamClass.java:72)
at java.io.ObjectStreamClass$2.run(ObjectStreamClass.java:484)
at java.io.ObjectStreamClass$2.run(ObjectStreamClass.java:472)
at java.security.AccessController.doPrivileged(Native Method)
at java.io.ObjectStreamClass.<init>(ObjectStreamClass.java:472)
at java.io.ObjectStreamClass.lookup(ObjectStreamClass.java:369)
at java.io.ObjectStreamClass.initNonProxy(ObjectStreamClass.java:598)
at java.io.ObjectInputStream.readNonProxyDesc(ObjectInputStream.java:1843)
at java.io.ObjectInputStream.readClassDesc(ObjectInputStream.java:1713)
at java.io.ObjectInputStream.readOrdinaryObject(ObjectInputStream.java:2000)
at java.io.ObjectInputStream.readObject0(ObjectInputStream.java:1535)
at java.io.ObjectInputStream.readObject(ObjectInputStream.java:422)
at hudson.remoting.UserRequest.deserialize(UserRequest.java:245)
at hudson.remoting.UserRequest.perform(UserRequest.java:159)
at hudson.remoting.UserRequest.perform(UserRequest.java:52)
at hudson.remoting.Request$2.run(Request.java:336)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:68)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:748)
at ......remote call to ubuntu-1(Native Method)
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1554)
at hudson.remoting.UserResponse.retrieve(UserRequest.java:281)
at hudson.remoting.Channel.call(Channel.java:839)
Caused: java.io.IOException: Remote call on ubuntu-1 failed
at hudson.remoting.Channel.call(Channel.java:847)
at hudson.FilePath.act(FilePath.java:987)
Caused: java.io.IOException: remote file operation failed: /home/jenkins/jenkins-slave/workspace/Mesos-Buildbot/bf5755a9 at hudson.remoting.Channel@1117e8b9:ubuntu-1
at hudson.FilePath.act(FilePath.java:994)
at hudson.FilePath.act(FilePath.java:976)
at hudson.FilePath.toURI(FilePath.java:1121)
at hudson.tasks.MailSender.createFailureMail(MailSender.java:319)
at hudson.tasks.MailSender.createMail(MailSender.java:181)
at hudson.tasks.MailSender.run(MailSender.java:112)
at hudson.tasks.Mailer.perform(Mailer.java:170)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:736)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:682)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:627)
at hudson.model.Run.execute(Run.java:1762)
at hudson.matrix.MatrixRun.run(MatrixRun.java:146)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:419)
Jenkins build is back to normal : Mesos-Buildbot » cmake,clang,--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=clang,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>
Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--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=clang,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:
[vinodkone] Added `--disable-libtool-wrappers` to support scripts.
[bmahler] Mesos UI: extract the agent URL generation to a function.
[bbannier] Formatted 'synchronized' like loop instead of function.
------------------------------------------
[...truncated 30.32 MB...]
3: I1009 14:21:03.846858 15906 registrar.cpp:391] Successfully fetched the registry (0B) in 0ns
3: I1009 14:21:03.846957 15906 registrar.cpp:495] Applied 1 operations in 34186ns; attempting to update the registry
3: I1009 14:21:03.847477 15906 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1009 14:21:03.847579 15906 registrar.cpp:424] Successfully recovered registrar
3: I1009 14:21:03.847929 15905 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
3: I1009 14:21:03.847913 15900 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: W1009 14:21:03.853114 15885 process.cpp:3194] Attempted to spawn already running process files@172.17.0.3:43927
3: I1009 14:21:03.854055 15885 containerizer.cpp:292] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1009 14:21:03.854532 15885 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
3: W1009 14:21:03.854650 15885 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1009 14:21:03.854681 15885 provisioner.cpp:255] Using default backend 'copy'
3: I1009 14:21:03.856454 15885 cluster.cpp:448] Creating default 'local' authorizer
3: I1009 14:21:03.858417 15905 slave.cpp:254] Mesos agent started on (825)@172.17.0.3:43927
3: I1009 14:21:03.858449 15905 slave.cpp:255] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_UMQlCz/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_UMQlCz/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_UMQlCz/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_UMQlCz/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_UMQlCz/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_eudRrT\/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_eudRrT\/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_UMQlCz" --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_jL35we" --zk_session_timeout="10secs"
3: I1009 14:21:03.858806 15905 credentials.hpp:86] Loading credential for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_UMQlCz/credential'
3: I1009 14:21:03.858947 15905 slave.cpp:287] Agent using credential for: test-principal
3: W1009 14:21:03.858947 15885 process.cpp:3194] Attempted to spawn already running process version@172.17.0.3:43927
3: I1009 14:21:03.858970 15905 credentials.hpp:37] Loading credentials for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_UMQlCz/http_credentials'
3: I1009 14:21:03.859174 15905 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1009 14:21:03.859328 15905 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1009 14:21:03.859791 15885 sched.cpp:232] Version: 1.5.0
3: I1009 14:21:03.860276 15894 sched.cpp:336] New master detected at master@172.17.0.3:43927
3: I1009 14:21:03.860383 15894 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1009 14:21:03.860402 15894 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.3:43927
3: I1009 14:21:03.860519 15894 sched.cpp:853] Will retry registration in 1.57417486secs if necessary
3: I1009 14:21:03.860738 15907 master.cpp:2929] Received SUBSCRIBE call for framework 'default' at scheduler-642f4797-7dd2-42da-bc02-174afc73c4c7@172.17.0.3:43927
3: I1009 14:21:03.860764 15907 master.cpp:2263] Authorizing framework principal 'test-principal' to receive offers for roles '{ default-role }'
3: I1009 14:21:03.861232 15893 master.cpp:3009] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1009 14:21:03.862059 15897 sched.cpp:747] Framework registered with 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000
3: I1009 14:21:03.862113 15897 sched.cpp:761] Scheduler::registered took 29991ns
3: I1009 14:21:03.862167 15890 hierarchical.cpp:303] Added framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000
3: I1009 14:21:03.862345 15890 hierarchical.cpp:1943] No allocations performed
3: I1009 14:21:03.862385 15890 hierarchical.cpp:2033] No inverse offers to send out!
3: I1009 14:21:03.862416 15890 hierarchical.cpp:1486] Performed allocation for 0 agents in 120273ns
3: I1009 14:21:03.862345 15905 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_eudRrT\/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_eudRrT\/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: I1009 14:21:03.862725 15905 slave.cpp:593] Agent attributes: [ ]
3: I1009 14:21:03.862735 15905 slave.cpp:602] Agent hostname: 8258ddd81e7c
3: I1009 14:21:03.862898 15906 status_update_manager.cpp:177] Pausing sending status updates
3: I1009 14:21:03.864317 15899 state.cpp:64] Recovering state from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_jL35we/meta'
3: I1009 14:21:03.864589 15898 status_update_manager.cpp:203] Recovering status update manager
3: I1009 14:21:03.864792 15896 containerizer.cpp:648] Recovering containerizer
3: I1009 14:21:03.866305 15900 provisioner.cpp:416] Provisioner recovery complete
3: I1009 14:21:03.866681 15906 slave.cpp:6326] Finished recovery
3: I1009 14:21:03.867280 15906 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1009 14:21:03.867501 15906 slave.cpp:993] New master detected at master@172.17.0.3:43927
3: I1009 14:21:03.867508 15903 status_update_manager.cpp:177] Pausing sending status updates
3: I1009 14:21:03.867568 15906 slave.cpp:1028] Detecting new master
3: I1009 14:21:03.867668 15906 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1009 14:21:03.869174 15899 slave.cpp:1055] Authenticating with master master@172.17.0.3:43927
3: I1009 14:21:03.869232 15899 slave.cpp:1064] Using default CRAM-MD5 authenticatee
3: I1009 14:21:03.869498 15902 authenticatee.cpp:121] Creating new client SASL connection
3: I1009 14:21:03.869668 15898 hierarchical.cpp:1943] No allocations performed
3: I1009 14:21:03.869706 15898 hierarchical.cpp:2033] No inverse offers to send out!
3: I1009 14:21:03.869736 15898 hierarchical.cpp:1486] Performed allocation for 0 agents in 116551ns
3: I1009 14:21:03.869788 15894 master.cpp:7936] Authenticating slave(825)@172.17.0.3:43927
3: I1009 14:21:03.869969 15901 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1490)@172.17.0.3:43927
3: I1009 14:21:03.870205 15908 authenticator.cpp:98] Creating new server SASL connection
3: I1009 14:21:03.870455 15887 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1009 14:21:03.870486 15887 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1009 14:21:03.870599 15907 authenticator.cpp:204] Received SASL authentication start
3: I1009 14:21:03.870653 15907 authenticator.cpp:326] Authentication requires more steps
3: I1009 14:21:03.870777 15888 authenticatee.cpp:259] Received SASL authentication step
3: I1009 14:21:03.870918 15896 authenticator.cpp:232] Received SASL authentication step
3: I1009 14:21:03.870946 15896 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8258ddd81e7c' server FQDN: '8258ddd81e7c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1009 14:21:03.870957 15896 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1009 14:21:03.870993 15896 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1009 14:21:03.871014 15896 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8258ddd81e7c' server FQDN: '8258ddd81e7c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1009 14:21:03.871021 15896 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1009 14:21:03.871026 15896 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1009 14:21:03.871038 15896 authenticator.cpp:318] Authentication success
3: I1009 14:21:03.871142 15893 authenticatee.cpp:299] Authentication success
3: I1009 14:21:03.871232 15889 master.cpp:7966] Successfully authenticated principal 'test-principal' at slave(825)@172.17.0.3:43927
3: I1009 14:21:03.871278 15892 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1490)@172.17.0.3:43927
3: I1009 14:21:03.871496 15893 slave.cpp:1147] Successfully authenticated with master master@172.17.0.3:43927
3: I1009 14:21:03.871778 15893 slave.cpp:1642] Will retry registration in 1.1398ms if necessary
3: I1009 14:21:03.872004 15891 master.cpp:5801] Received register agent message from slave(825)@172.17.0.3:43927 (8258ddd81e7c)
3: I1009 14:21:03.872045 15891 master.cpp:3838] Authorizing agent with principal 'test-principal'
3: I1009 14:21:03.872472 15906 master.cpp:5861] Authorized registration of agent at slave(825)@172.17.0.3:43927 (8258ddd81e7c)
3: I1009 14:21:03.872582 15906 master.cpp:5954] Registering agent at slave(825)@172.17.0.3:43927 (8258ddd81e7c) with id 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0
3: I1009 14:21:03.872956 15905 registrar.cpp:495] Applied 1 operations in 77850ns; attempting to update the registry
3: I1009 14:21:03.873486 15905 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1009 14:21:03.873695 15898 master.cpp:6001] Admitted agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 at slave(825)@172.17.0.3:43927 (8258ddd81e7c)
3: I1009 14:21:03.874348 15901 slave.cpp:4982] Received ping from slave-observer(742)@172.17.0.3:43927
3: I1009 14:21:03.874496 15901 slave.cpp:1193] Registered with master master@172.17.0.3:43927; given agent ID 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0
3: I1009 14:21:03.874593 15907 status_update_manager.cpp:184] Resuming sending status updates
3: I1009 14:21:03.874791 15901 slave.cpp:1213] Checkpointing SlaveInfo to '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_jL35we/meta/slaves/0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0/slave.info'
3: I1009 14:21:03.874452 15898 master.cpp:6032] Registered agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 at slave(825)@172.17.0.3:43927 (8258ddd81e7c) 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_eudRrT\/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_eudRrT\/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: I1009 14:21:03.875181 15901 slave.cpp:1263] Forwarding total resources cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk2]:2048; ports:[31000-32000]
3: I1009 14:21:03.875217 15908 hierarchical.cpp:593] Added agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 (8258ddd81e7c) with cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk2]:2048; ports:[31000-32000] (allocated: {})
3: I1009 14:21:03.875298 15901 slave.cpp:1272] Forwarding total oversubscribed resources {}
3: I1009 14:21:03.875648 15888 master.cpp:6804] Received update of agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 at slave(825)@172.17.0.3:43927 (8258ddd81e7c) with total resources cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk2]:2048; ports:[31000-32000]
3: I1009 14:21:03.875733 15888 master.cpp:6817] Received update of agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 at slave(825)@172.17.0.3:43927 (8258ddd81e7c) with total oversubscribed resources {}
3: I1009 14:21:03.875917 15888 master.cpp:6828] Ignoring update on agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 at slave(825)@172.17.0.3:43927 (8258ddd81e7c) as it reports no changes
3: I1009 14:21:03.876749 15908 hierarchical.cpp:2033] No inverse offers to send out!
3: I1009 14:21:03.876785 15908 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.402895ms
3: I1009 14:21:03.877465 15896 master.cpp:7766] Sending 1 offers to framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000 (default) at scheduler-642f4797-7dd2-42da-bc02-174afc73c4c7@172.17.0.3:43927
3: I1009 14:21:03.878031 15897 sched.cpp:917] Scheduler::resourceOffers took 111533ns
3: I1009 14:21:03.879817 15889 master.cpp:9389] Removing offer 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-O0
3: I1009 14:21:03.879936 15889 master.cpp:4196] Processing ACCEPT call for offers: [ 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-O0 ] on agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 at slave(825)@172.17.0.3:43927 (8258ddd81e7c) for framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000 (default) at scheduler-642f4797-7dd2-42da-bc02-174afc73c4c7@172.17.0.3:43927
3: I1009 14:21:03.880086 15889 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_eudRrT\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1009 14:21:03.880982 15889 master.cpp:2348] Dropping CREATE offer operation from framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000 (default) at scheduler-642f4797-7dd2-42da-bc02-174afc73c4c7@172.17.0.3:43927: Not authorized to create persistent volumes as 'test-principal'
3: I1009 14:21:03.882390 15891 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_eudRrT/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk2]:2048; ports:[31000-32000], allocated: {}) on agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 from framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000
3: I1009 14:21:03.885475 15886 hierarchical.cpp:2033] No inverse offers to send out!
3: I1009 14:21:03.885517 15886 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.432017ms
3: I1009 14:21:03.886152 15895 master.cpp:7766] Sending 1 offers to framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000 (default) at scheduler-642f4797-7dd2-42da-bc02-174afc73c4c7@172.17.0.3:43927
3: I1009 14:21:03.886654 15895 sched.cpp:917] Scheduler::resourceOffers took 98475ns
3: I1009 14:21:03.887423 15905 master.cpp:5207] Processing DECLINE call for offers: [ 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-O1 ] for framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000 (default) at scheduler-642f4797-7dd2-42da-bc02-174afc73c4c7@172.17.0.3:43927
3: W1009 14:21:03.887532 15885 process.cpp:3194] Attempted to spawn already running process version@172.17.0.3:43927
3: I1009 14:21:03.888376 15885 sched.cpp:232] Version: 1.5.0
3: I1009 14:21:03.888495 15905 master.cpp:9389] Removing offer 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-O1
3: I1009 14:21:03.888650 15905 master.cpp:3439] Processing SUPPRESS call for framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000 (default) at scheduler-642f4797-7dd2-42da-bc02-174afc73c4c7@172.17.0.3:43927
3: I1009 14:21:03.888842 15888 sched.cpp:336] New master detected at master@172.17.0.3:43927
3: I1009 14:21:03.888928 15888 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1009 14:21:03.888942 15888 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.3:43927
3: I1009 14:21:03.889037 15888 sched.cpp:853] Will retry registration in 1.373035188secs if necessary
3: I1009 14:21:03.889011 15894 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_eudRrT/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk2]:2048; ports:[31000-32000], allocated: {}) on agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 from framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000
3: I1009 14:21:03.889168 15894 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000
3: I1009 14:21:03.889187 15908 master.cpp:2929] Received SUBSCRIBE call for framework 'creator-framework' at scheduler-b107ec9b-5944-46bd-995a-550d6332527e@172.17.0.3:43927
3: I1009 14:21:03.889205 15908 master.cpp:2263] Authorizing framework principal 'creator-principal' to receive offers for roles '{ default-role }'
3: I1009 14:21:03.889642 15897 master.cpp:3009] Subscribing framework creator-framework with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1009 14:21:03.890069 15890 hierarchical.cpp:303] Added framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001
3: I1009 14:21:03.890144 15897 sched.cpp:747] Framework registered with 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001
3: I1009 14:21:03.890180 15897 sched.cpp:761] Scheduler::registered took 22475ns
3: I1009 14:21:03.891484 15890 hierarchical.cpp:2033] No inverse offers to send out!
3: I1009 14:21:03.891520 15890 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.3368ms
3: I1009 14:21:03.892181 15904 master.cpp:7766] Sending 1 offers to framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001 (creator-framework) at scheduler-b107ec9b-5944-46bd-995a-550d6332527e@172.17.0.3:43927
3: I1009 14:21:03.892663 15904 sched.cpp:917] Scheduler::resourceOffers took 117093ns
3: I1009 14:21:03.894289 15891 hierarchical.cpp:1943] No allocations performed
3: I1009 14:21:03.894327 15891 hierarchical.cpp:2033] No inverse offers to send out!
3: I1009 14:21:03.894358 15891 hierarchical.cpp:1486] Performed allocation for 1 agents in 155805ns
3: I1009 14:21:03.895928 15903 master.cpp:9389] Removing offer 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-O2
3: I1009 14:21:03.896036 15903 master.cpp:4196] Processing ACCEPT call for offers: [ 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-O2 ] on agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 at slave(825)@172.17.0.3:43927 (8258ddd81e7c) for framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001 (creator-framework) at scheduler-b107ec9b-5944-46bd-995a-550d6332527e@172.17.0.3:43927
3: I1009 14:21:03.896172 15903 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_eudRrT\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: I1009 14:21:03.897649 15903 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_eudRrT\/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 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001 (creator-framework) at scheduler-b107ec9b-5944-46bd-995a-550d6332527e@172.17.0.3:43927 to agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 at slave(825)@172.17.0.3:43927 (8258ddd81e7c)
3: I1009 14:21:03.898254 15903 master.cpp:9335] Sending updated checkpointed resources disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk1,id1:path1]:2048 to agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 at slave(825)@172.17.0.3:43927 (8258ddd81e7c)
3: I1009 14:21:03.899514 15898 slave.cpp:3535] Updated checkpointed resources from {} to disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk1,id1:path1]:2048
3: I1009 14:21:03.901734 15907 hierarchical.cpp:887] Updated allocation of framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001 on agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-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_eudRrT/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/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_eudRrT/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk1,id1:path1]:2048
3: I1009 14:21:03.903093 15907 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_eudRrT/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk1,id1:path1]:2048, allocated: {}) on agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 from framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001
3: I1009 14:21:03.906059 15901 hierarchical.cpp:2033] No inverse offers to send out!
3: I1009 14:21:03.906102 15901 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.348095ms
3: I1009 14:21:03.906736 15894 master.cpp:7766] Sending 1 offers to framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001 (creator-framework) at scheduler-b107ec9b-5944-46bd-995a-550d6332527e@172.17.0.3:43927
3: I1009 14:21:03.907203 15896 sched.cpp:917] Scheduler::resourceOffers took 91926ns
3: I1009 14:21:03.907935 15909 master.cpp:5207] Processing DECLINE call for offers: [ 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-O3 ] for framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001 (creator-framework) at scheduler-b107ec9b-5944-46bd-995a-550d6332527e@172.17.0.3:43927
3: I1009 14:21:03.908846 15909 master.cpp:9389] Removing offer 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-O3
3: I1009 14:21:03.908994 15909 master.cpp:3439] Processing SUPPRESS call for framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001 (creator-framework) at scheduler-b107ec9b-5944-46bd-995a-550d6332527e@172.17.0.3:43927
3: I1009 14:21:03.909689 15900 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_eudRrT/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk1,id1:path1]:2048, allocated: {}) on agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 from framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001
3: I1009 14:21:03.909857 15900 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001
3: I1009 14:21:03.910189 15892 master.cpp:5312] Processing REVIVE call for framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000 (default) at scheduler-642f4797-7dd2-42da-bc02-174afc73c4c7@172.17.0.3:43927
3: I1009 14:21:03.910346 15890 hierarchical.cpp:1308] Revived offers for roles { default-role } of framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000
3: I1009 14:21:03.911731 15890 hierarchical.cpp:2033] No inverse offers to send out!
3: I1009 14:21:03.911767 15890 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.320684ms
3: I1009 14:21:03.912427 15893 master.cpp:7766] Sending 1 offers to framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000 (default) at scheduler-642f4797-7dd2-42da-bc02-174afc73c4c7@172.17.0.3:43927
3: I1009 14:21:03.912928 15904 sched.cpp:917] Scheduler::resourceOffers took 99750ns
3: I1009 14:21:03.914407 15889 master.cpp:9389] Removing offer 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-O4
3: I1009 14:21:03.914517 15889 master.cpp:4196] Processing ACCEPT call for offers: [ 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-O4 ] on agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 at slave(825)@172.17.0.3:43927 (8258ddd81e7c) for framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000 (default) at scheduler-642f4797-7dd2-42da-bc02-174afc73c4c7@172.17.0.3:43927
3: I1009 14:21:03.914639 15889 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_eudRrT\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1009 14:21:03.915498 15889 master.cpp:2348] Dropping DESTROY offer operation from framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000 (default) at scheduler-642f4797-7dd2-42da-bc02-174afc73c4c7@172.17.0.3:43927: Not authorized to destroy persistent volumes as 'test-principal'
3: I1009 14:21:03.916883 15889 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_eudRrT/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk1,id1:path1]:2048, allocated: {}) on agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 from framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000
3: I1009 14:21:03.919687 15903 hierarchical.cpp:2033] No inverse offers to send out!
3: I1009 14:21:03.919728 15903 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.321961ms
3: I1009 14:21:03.920437 15907 master.cpp:7766] Sending 1 offers to framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000 (default) at scheduler-642f4797-7dd2-42da-bc02-174afc73c4c7@172.17.0.3:43927
3: I1009 14:21:03.920925 15905 sched.cpp:917] Scheduler::resourceOffers took 96837ns
3: I1009 14:21:03.921253 15885 sched.cpp:2005] Asked to stop the driver
3: I1009 14:21:03.921391 15888 sched.cpp:1187] Stopping framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000
3: I1009 14:21:03.921631 15885 sched.cpp:2005] Asked to stop the driver
3: I1009 14:21:03.921622 15901 master.cpp:8447] Processing TEARDOWN call for framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000 (default) at scheduler-642f4797-7dd2-42da-bc02-174afc73c4c7@172.17.0.3:43927
3: I1009 14:21:03.921665 15901 master.cpp:8459] Removing framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000 (default) at scheduler-642f4797-7dd2-42da-bc02-174afc73c4c7@172.17.0.3:43927
3: I1009 14:21:03.921691 15901 master.cpp:3299] Deactivating framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000 (default) at scheduler-642f4797-7dd2-42da-bc02-174afc73c4c7@172.17.0.3:43927
3: I1009 14:21:03.921727 15908 sched.cpp:1187] Stopping framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001
3: I1009 14:21:03.921838 15909 hierarchical.cpp:412] Deactivated framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000
3: I1009 14:21:03.922587 15901 master.cpp:9389] Removing offer 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-O5
3: I1009 14:21:03.922816 15886 slave.cpp:3227] Asked to shut down framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000 by master@172.17.0.3:43927
3: I1009 14:21:03.922849 15886 slave.cpp:3242] Cannot shut down unknown framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000
3: I1009 14:21:03.923086 15901 master.cpp:8447] Processing TEARDOWN call for framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001 (creator-framework) at scheduler-b107ec9b-5944-46bd-995a-550d6332527e@172.17.0.3:43927
3: I1009 14:21:03.923112 15901 master.cpp:8459] Removing framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001 (creator-framework) at scheduler-b107ec9b-5944-46bd-995a-550d6332527e@172.17.0.3:43927
3: I1009 14:21:03.923128 15901 master.cpp:3299] Deactivating framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001 (creator-framework) at scheduler-b107ec9b-5944-46bd-995a-550d6332527e@172.17.0.3:43927
3: I1009 14:21:03.923280 15903 slave.cpp:3227] Asked to shut down framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001 by master@172.17.0.3:43927
3: I1009 14:21:03.923310 15903 slave.cpp:3242] Cannot shut down unknown framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001
3: I1009 14:21:03.923355 15900 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_eudRrT/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_eudRrT/disk1,id1:path1]:2048, allocated: {}) on agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 from framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000
3: I1009 14:21:03.923545 15900 hierarchical.cpp:355] Removed framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0000
3: I1009 14:21:03.923599 15900 hierarchical.cpp:412] Deactivated framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001
3: I1009 14:21:03.923789 15900 hierarchical.cpp:355] Removed framework 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-0001
3: I1009 14:21:03.923992 15885 slave.cpp:869] Agent terminating
3: I1009 14:21:03.924165 15888 master.cpp:1303] Agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 at slave(825)@172.17.0.3:43927 (8258ddd81e7c) disconnected
3: I1009 14:21:03.924190 15888 master.cpp:3336] Disconnecting agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 at slave(825)@172.17.0.3:43927 (8258ddd81e7c)
3: I1009 14:21:03.924238 15888 master.cpp:3355] Deactivating agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 at slave(825)@172.17.0.3:43927 (8258ddd81e7c)
3: I1009 14:21:03.924306 15892 hierarchical.cpp:690] Agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0 deactivated
3: I1009 14:21:03.930099 15885 master.cpp:1145] Master terminating
3: I1009 14:21:03.930975 15907 hierarchical.cpp:626] Removed agent 0d5fa4f8-2f6d-4c44-897c-f7ef3ac775f9-S0
3: [ OK ] DiskResource/PersistentVolumeTest.BadACLDropCreateAndDestroy/1 (99 ms)
3: [----------] 34 tests from DiskResource/PersistentVolumeTest (11957 ms total)
3:
3: [----------] Global test environment tear-down
3: [==========] 1641 tests from 178 test cases ran. (317858 ms total)
3: [ PASSED ] 1640 tests.
3: [ FAILED ] 1 test, listed below:
3: [ FAILED ] OversubscriptionTest.ForwardUpdateSlaveMessage
3:
3: 1 FAILED TEST
3: YOU HAVE 21 DISABLED TESTS
3:
3: I1009 14:21:03.996413 15910 process.cpp:1068] Failed to accept socket: future discarded
3/3 Test #3: MesosTests .......................***Failed 323.13 sec
67% tests passed, 1 tests failed out of 3
Total Test time (real) = 342.81 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-1507557284-24822
Untagged: mesos-1507557284-24822:latest
Deleted: sha256:171cb71eecea2e797b5652ad1619c1fa0e7b051cb64499039e8b246a5a2d9446
Deleted: sha256:4bdcba635157bc3dbea107c2096b212e7ef174ded431880a519172862b4d8a86
Deleted: sha256:34ad0c415ebc330f861445fd32a0021b5e7128f428e84c78504da59bd87e0c30
Deleted: sha256:e2506063f8eb38ff0d9a7bf107b0a1836625736618da57b7237570a46dac41ac
Deleted: sha256:d71a06034cb40442f573926a73d7a7d6f69ad73f3550bfd4d9630dec918911e3
Deleted: sha256:140d3d005f6bf19e62ad8ae7674493f21751d71292942241d63874033bbdd3aa
Deleted: sha256:fd4dbcf9899889862148102b1416be4849a810d37f05d3592fb3d43d24dbd73b
Deleted: sha256:b74e59c9956df3a4628394b4ee0e5ebb082e5985ae40f1a272c2b083b0421e06
Deleted: sha256:bf23497536e3cfdf2060f10d2dea0a528d18486b1ff7ae8f34e481d2979dca9a
Deleted: sha256:6a6f091bebaf1a7ad32cb9469eb5388c49c0da2fcdde555b569ff1bd495ea47b
Deleted: sha256:1c5488a7a767973dc9dfde604505fc72b8c745d6312266bb8d066d93a25f0c52
Deleted: sha256:64ec0a9a965bcf895df1eb73419fabbb6fe268e92e9c5f4c59bdc8a2bdb74b9e
Deleted: sha256:866b43bb37823389beadd1b8e3403dd9d621933cf799c27936a201dcb391eb64
Deleted: sha256:7e18579452176620c75abd48b8a498f94e6089eeed1cd1c8231586a4b9db040c
Deleted: sha256:c3ffdcf4a5f5b1f744215c8bcd8b3323df8012ce2d7455e306cac22ef9d03649
Deleted: sha256:69ced180bd339753a3d0504d0ef90799e6e23f2873bf7a15474cbb87059e9fbd
Deleted: sha256:3c62ebd600dd295c5f78c0932f4ccd5978a0234600320b4cb483f4824479bae7
Deleted: sha256:bd13c1e1dac4debbf1c7a919a9d5bb7e37f45d453d3085f48612543840282690
Deleted: sha256:524738a6ace26c7046f96bd187de5c90054be2d7a8749a6ed31489c1f05dc142
Deleted: sha256:f6ccfcd0ff743cad1c387a48335dcdd3754f2f3313efa7d57cde6c01c8d9f01f
Deleted: sha256:ff632112d66ecc67088c97ecb42f4fc7b619cc7e1e2ef60351c31fe465020bab
Deleted: sha256:1757bafe7f7c2b496dad9537f39180d5c66ce53a9f345d1a8d847ae824a738ac
Deleted: sha256:e15e92adea8945c7db0d2eb1e8a0b6757548e491f26f2e7a2051a72fc7ee9165
Deleted: sha256:5a9b086cf0b021519db53e14d5a3fadaad15187a49eb910a773fcc3c2cd35dfb
Deleted: sha256:35cf78d31c143dcb8733a731eda4fa0a05641b7b78cd672940f3859c1d13606d
Deleted: sha256:aa0d7919b2434e4a09e42b41365ad5db9365d00597da2ee6e11a07ec67d9cb6f
Deleted: sha256:498dd1795aa2cf3acb9ffc78e35542d0bc08899b809921c2c71c4353eae75915
Deleted: sha256:68cd9bd80868624a0ffb40016783dd9c6a67f5c932cd8570c2593627ceaf3fef
Build step 'Execute shell' marked build as failure
Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--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=clang,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>
------------------------------------------
Failed to access build log
java.lang.OutOfMemoryError: GC overhead limit exceeded
at java.lang.Class.getDeclaredFields0(Native Method)
at java.lang.Class.privateGetDeclaredFields(Class.java:2583)
at java.lang.Class.getDeclaredField(Class.java:2068)
at java.io.ObjectStreamClass.getDeclaredSUID(ObjectStreamClass.java:1703)
at java.io.ObjectStreamClass.access$700(ObjectStreamClass.java:72)
at java.io.ObjectStreamClass$2.run(ObjectStreamClass.java:484)
at java.io.ObjectStreamClass$2.run(ObjectStreamClass.java:472)
at java.security.AccessController.doPrivileged(Native Method)
at java.io.ObjectStreamClass.<init>(ObjectStreamClass.java:472)
at java.io.ObjectStreamClass.lookup(ObjectStreamClass.java:369)
at java.io.ObjectStreamClass.initNonProxy(ObjectStreamClass.java:598)
at java.io.ObjectInputStream.readNonProxyDesc(ObjectInputStream.java:1843)
at java.io.ObjectInputStream.readClassDesc(ObjectInputStream.java:1713)
at java.io.ObjectInputStream.readOrdinaryObject(ObjectInputStream.java:2000)
at java.io.ObjectInputStream.readObject0(ObjectInputStream.java:1535)
at java.io.ObjectInputStream.readObject(ObjectInputStream.java:422)
at hudson.remoting.UserRequest.deserialize(UserRequest.java:245)
at hudson.remoting.UserRequest.perform(UserRequest.java:159)
at hudson.remoting.UserRequest.perform(UserRequest.java:52)
at hudson.remoting.Request$2.run(Request.java:336)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:68)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:748)
at ......remote call to ubuntu-1(Native Method)
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1554)
at hudson.remoting.UserResponse.retrieve(UserRequest.java:281)
at hudson.remoting.Channel.call(Channel.java:839)
Caused: java.io.IOException: Remote call on ubuntu-1 failed
at hudson.remoting.Channel.call(Channel.java:847)
at hudson.FilePath.act(FilePath.java:987)
Caused: java.io.IOException: remote file operation failed: /home/jenkins/jenkins-slave/workspace/Mesos-Buildbot/bf5755a9 at hudson.remoting.Channel@1117e8b9:ubuntu-1
at hudson.FilePath.act(FilePath.java:994)
at hudson.FilePath.act(FilePath.java:976)
at hudson.FilePath.toURI(FilePath.java:1121)
at hudson.tasks.MailSender.createFailureMail(MailSender.java:319)
at hudson.tasks.MailSender.createMail(MailSender.java:181)
at hudson.tasks.MailSender.run(MailSender.java:112)
at hudson.tasks.Mailer.perform(Mailer.java:170)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:736)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:682)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:627)
at hudson.model.Run.execute(Run.java:1762)
at hudson.matrix.MatrixRun.run(MatrixRun.java:146)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:419)