Return-Path: X-Original-To: archive-asf-public-internal@cust-asf2.ponee.io Delivered-To: archive-asf-public-internal@cust-asf2.ponee.io Received: from cust-asf.ponee.io (cust-asf.ponee.io [163.172.22.183]) by cust-asf2.ponee.io (Postfix) with ESMTP id 43737200C11 for ; Sat, 4 Feb 2017 10:41:10 +0100 (CET) Received: by cust-asf.ponee.io (Postfix) id 41F86160B63; Sat, 4 Feb 2017 09:41:10 +0000 (UTC) Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by cust-asf.ponee.io (Postfix) with SMTP id DED0D160B56 for ; Sat, 4 Feb 2017 10:41:08 +0100 (CET) Received: (qmail 12407 invoked by uid 500); 4 Feb 2017 09:41:08 -0000 Mailing-List: contact builds-help@mesos.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: builds@mesos.apache.org Delivered-To: mailing list builds@mesos.apache.org Received: (qmail 12391 invoked by uid 99); 4 Feb 2017 09:41:07 -0000 Received: from crius.apache.org (HELO crius.apache.org) (140.211.11.14) by apache.org (qpsmtpd/0.29) with ESMTP; Sat, 04 Feb 2017 09:41:07 +0000 Received: from crius.apache.org (localhost [127.0.0.1]) by crius.apache.org (ASF Mail Server at crius.apache.org) with ESMTP id ABFC09C0189; Sat, 4 Feb 2017 09:41:07 +0000 (UTC) Date: Sat, 4 Feb 2017 09:38:25 +0000 (UTC) From: Apache Jenkins Server To: builds@mesos.apache.org, xujyan@apple.com, bmahler@apache.org Message-ID: <1850141084.7330.1486201267699.JavaMail.jenkins@crius> In-Reply-To: <1924064240.7252.1486176737558.JavaMail.jenkins@crius> References: <1924064240.7252.1486176737558.JavaMail.jenkins@crius> Subject: =?UTF-8?Q?Build_failed_in_Jenkins:_Mesos-Buildbo?= =?UTF-8?Q?t_=C2=BB_cmake,clang,--verbose,GLOG=5Fv=3D1_MESO?= =?UTF-8?Q?S=5FVERBOSE=3D1,ubuntu:14.04,(docker||Hado?= =?UTF-8?Q?op)&&(!ubuntu-us1)&&(!ubuntu-eu2)_#3219?= MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable X-Instance-Identity: MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEAkqVKZPv7YyHBB3FvWfV7XQehwe/Ga3aadzSNknt8g382X3uN8A3SOQ+Ixq9HxS+ZlN6XR4TECySmSRy2JN5Rx8svxAD0TjtSF9LuU98dD+LniNDP7Lq6gvRFuJhbMHoS0nuTizDZLsK4X8TW5MyV9w+jFbdoZfRE5O/Mse0fkOeL5uoIS/3Vvu/W+x9QSjDkB7CaU56bPFlQjqqJBl3Cn9r34CkXQZYnLb/NjW4vcpw0+TgMUAPTIVEr5BTPZRshz19g7huwg3zANT5HBIZnzV4hsVY9w4JHkceFdKi/ibNnjPjsFs9pm0HSGJ/RDxjIvSTYT02eH4+m1RAYaj2E9QIDAQAB X-Jenkins-Job: Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1 MESOS_VERBOSE=1,OS=ubuntu:14.04,label_exp=(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) X-Jenkins-Result: FAILURE archived-at: Sat, 04 Feb 2017 09:41:10 -0000 See Changes: [bmahler] Update the allocator to handle frameworks with multiple roles. [bmahler] Update the allocator unit tests to reflect MULTI_ROLE support. [bmahler] Updated the master to handle non-MULTI_ROLE schedulers. [bmahler] Updated the master's HTTP operations to handle MULTI_ROLE changes= . [bmahler] Updated the agent to be MULTI_ROLE capable. [bmahler] Updated master to handle non-MULTI_ROLE agents. [bmahler] Fixed MULTI_ROLE related bugs when updating framework info. [bmahler] Update the tests to handle MULTI_ROLE support. ------------------------------------------ [...truncated 134465 lines...] I0204 05:19:47.224320 24827 master.cpp:5949] Received update of agent 3e022= 727-931f-4251-9cc6-9b0b8b7909e0-S0 at slave(516)@172.17.0.2:54543 (dfb4d799= 4de8) with total oversubscribed resources {} I0204 05:19:47.224467 24825 hierarchical.cpp:286] Added framework 3e022727-= 931f-4251-9cc6-9b0b8b7909e0-0000 I0204 05:19:47.224484 24825 hierarchical.cpp:415] Deactivated framework 3e0= 22727-931f-4251-9cc6-9b0b8b7909e0-0000 I0204 05:19:47.224536 24822 slave.cpp:2845] Ignoring new checkpointed resou= rces identical to the current version: {} I0204 05:19:47.224684 24825 hierarchical.cpp:1802] No allocations performed I0204 05:19:47.224704 24825 hierarchical.cpp:1892] No inverse offers to sen= d out! I0204 05:19:47.224715 24825 hierarchical.cpp:1404] Performed allocation for= 1 agents in 63809ns I0204 05:19:47.224761 24825 hierarchical.cpp:588] Agent 3e022727-931f-4251-= 9cc6-9b0b8b7909e0-S0 (dfb4d7994de8) updated with oversubscribed resources {= } (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], all= ocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocat= ed: *):1024; ports(*)(allocated: *):[31000-32000]) I0204 05:19:47.224817 24825 hierarchical.cpp:1802] No allocations performed I0204 05:19:47.224828 24825 hierarchical.cpp:1892] No inverse offers to sen= d out! I0204 05:19:47.224838 24825 hierarchical.cpp:1404] Performed allocation for= 1 agents in 36881ns I0204 05:19:47.225682 24828 process.cpp:3697] Handling HTTP event for proce= ss 'master' with path: '/master/teardown' I0204 05:19:47.226074 24828 http.cpp:420] HTTP POST for /master/teardown fr= om 172.17.0.2:39988 I0204 05:19:47.226205 24828 master.cpp:7657] Removing framework 3e022727-93= 1f-4251-9cc6-9b0b8b7909e0-0000 (default) I0204 05:19:47.226243 24828 master.cpp:8242] Updating the state of task 211= c2c01-5614-4a83-b15d-d34aa46688eb of framework 3e022727-931f-4251-9cc6-9b0b= 8b7909e0-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I0204 05:19:47.226361 24828 master.cpp:8336] Removing task 211c2c01-5614-4a= 83-b15d-d34aa46688eb with resources cpus(*)(allocated: *):2; mem(*)(allocat= ed: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-3200= 0] of framework 3e022727-931f-4251-9cc6-9b0b8b7909e0-0000 on agent 3e022727= -931f-4251-9cc6-9b0b8b7909e0-S0 at slave(516)@172.17.0.2:54543 (dfb4d7994de= 8) I0204 05:19:47.226491 24828 slave.cpp:2613] Asked to shut down framework 3e= 022727-931f-4251-9cc6-9b0b8b7909e0-0000 by master@172.17.0.2:54543 I0204 05:19:47.226506 24828 slave.cpp:2638] Shutting down framework 3e02272= 7-931f-4251-9cc6-9b0b8b7909e0-0000 I0204 05:19:47.226517 24828 slave.cpp:5020] Shutting down executor '211c2c0= 1-5614-4a83-b15d-d34aa46688eb' of framework 3e022727-931f-4251-9cc6-9b0b8b7= 909e0-0000 at executor(1)@172.17.0.2:46491 I0204 05:19:47.226713 24828 hierarchical.cpp:1082] Recovered cpus(*)(alloca= ted: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(= allocated: *):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; p= orts(*):[31000-32000], allocated: {}) on agent 3e022727-931f-4251-9cc6-9b0b= 8b7909e0-S0 from framework 3e022727-931f-4251-9cc6-9b0b8b7909e0-0000 I0204 05:19:47.226781 24828 hierarchical.cpp:362] Removed framework 3e02272= 7-931f-4251-9cc6-9b0b8b7909e0-0000 I0204 05:19:47.226877 28586 exec.cpp:410] Executor asked to shutdown Received SHUTDOWN event Shutting down Sending SIGTERM to process tree at pid 28602 I0204 05:19:47.227967 24826 process.cpp:3697] Handling HTTP event for proce= ss 'master' with path: '/master/state' I0204 05:19:47.228193 24826 http.cpp:420] HTTP GET for /master/state from 1= 72.17.0.2:39989 I0204 05:19:47.230834 24812 sched.cpp:2021] Asked to stop the driver ISent SIGTERM to the following process trees: [=20 --- 28602 mesos-containerizer launch --help=3Dfalse --launch_info=3D{"comma= nd":{"shell":true,"value":"sleep 100"}} --unshare_namespace_mnt=3Dfalse=20 ] Scheduling escalation to SIGKILL in 3secs from now 0204 05:19:47.230937 24817 sched.cpp:1203] Stopping framework 3e022727-931f= -4251-9cc6-9b0b8b7909e0-0000 I0204 05:19:47.231871 24818 containerizer.cpp:2119] Destroying container e7= 130d71-1844-48ed-8fa6-80bac7128f74 in RUNNING state I0204 05:19:47.231982 24818 launcher.cpp:151] Asked to destroy container e7= 130d71-1844-48ed-8fa6-80bac7128f74 I0204 05:19:47.241549 24818 slave.cpp:4343] Got exited event for executor(1= )@172.17.0.2:46491 I0204 05:19:47.268440 24813 containerizer.cpp:2482] Container e7130d71-1844= -48ed-8fa6-80bac7128f74 has exited I0204 05:19:47.269767 24826 provisioner.cpp:484] Ignoring destroy request f= or unknown container e7130d71-1844-48ed-8fa6-80bac7128f74 I0204 05:19:47.270433 24825 slave.cpp:4706] Executor '211c2c01-5614-4a83-b1= 5d-d34aa46688eb' of framework 3e022727-931f-4251-9cc6-9b0b8b7909e0-0000 ter= minated with signal Killed I0204 05:19:47.270474 24825 slave.cpp:4806] Cleaning up executor '211c2c01-= 5614-4a83-b15d-d34aa46688eb' of framework 3e022727-931f-4251-9cc6-9b0b8b790= 9e0-0000 at executor(1)@172.17.0.2:46491 I0204 05:19:47.270730 24828 gc.cpp:55] Scheduling '/tmp/TeardownTest_Recove= redFrameworkAfterMasterFailover_HvVMML/slaves/3e022727-931f-4251-9cc6-9b0b8= b7909e0-S0/frameworks/3e022727-931f-4251-9cc6-9b0b8b7909e0-0000/executors/2= 11c2c01-5614-4a83-b15d-d34aa46688eb/runs/e7130d71-1844-48ed-8fa6-80bac7128f= 74' for gc 6.99999686770963days in the future I0204 05:19:47.270838 24825 slave.cpp:4894] Cleaning up framework 3e022727-= 931f-4251-9cc6-9b0b8b7909e0-0000 I0204 05:19:47.270912 24826 gc.cpp:55] Scheduling '/tmp/TeardownTest_Recove= redFrameworkAfterMasterFailover_HvVMML/slaves/3e022727-931f-4251-9cc6-9b0b8= b7909e0-S0/frameworks/3e022727-931f-4251-9cc6-9b0b8b7909e0-0000/executors/2= 11c2c01-5614-4a83-b15d-d34aa46688eb' for gc 6.99999686588148days in the fut= ure I0204 05:19:47.270942 24824 status_update_manager.cpp:285] Closing status u= pdate streams for framework 3e022727-931f-4251-9cc6-9b0b8b7909e0-0000 I0204 05:19:47.270974 24824 status_update_manager.cpp:531] Cleaning up stat= us update stream for task 211c2c01-5614-4a83-b15d-d34aa46688eb of framework= 3e022727-931f-4251-9cc6-9b0b8b7909e0-0000 I0204 05:19:47.270982 24826 gc.cpp:55] Scheduling '/tmp/TeardownTest_Recove= redFrameworkAfterMasterFailover_HvVMML/slaves/3e022727-931f-4251-9cc6-9b0b8= b7909e0-S0/frameworks/3e022727-931f-4251-9cc6-9b0b8b7909e0-0000' for gc 6.9= 999968643763days in the future I0204 05:19:47.271675 24821 slave.cpp:803] Agent terminating I0204 05:19:47.271827 24827 master.cpp:1263] Agent 3e022727-931f-4251-9cc6-= 9b0b8b7909e0-S0 at slave(516)@172.17.0.2:54543 (dfb4d7994de8) disconnected I0204 05:19:47.271863 24827 master.cpp:3117] Disconnecting agent 3e022727-9= 31f-4251-9cc6-9b0b8b7909e0-S0 at slave(516)@172.17.0.2:54543 (dfb4d7994de8) I0204 05:19:47.271903 24827 master.cpp:3136] Deactivating agent 3e022727-93= 1f-4251-9cc6-9b0b8b7909e0-S0 at slave(516)@172.17.0.2:54543 (dfb4d7994de8) I0204 05:19:47.272059 24815 hierarchical.cpp:617] Agent 3e022727-931f-4251-= 9cc6-9b0b8b7909e0-S0 deactivated I0204 05:19:47.274399 24818 master.cpp:1102] Master terminating I0204 05:19:47.274771 24822 hierarchical.cpp:548] Removed agent 3e022727-93= 1f-4251-9cc6-9b0b8b7909e0-S0 [ OK ] TeardownTest.RecoveredFrameworkAfterMasterFailover (228 ms) [----------] 8 tests from TeardownTest (340 ms total) [----------] 5 tests from TarTest [ RUN ] TarTest.File [ OK ] TarTest.File (191 ms) [ RUN ] TarTest.Directory [ OK ] TarTest.Directory (202 ms) [ RUN ] TarTest.ChangeDirectory [ OK ] TarTest.ChangeDirectory (202 ms) [ RUN ] TarTest.BZIP2CompressFile [ OK ] TarTest.BZIP2CompressFile (201 ms) [ RUN ] TarTest.GZIPChangeDirectory [ OK ] TarTest.GZIPChangeDirectory (201 ms) [----------] 5 tests from TarTest (998 ms total) [----------] 1 test from ShasumTest [ RUN ] ShasumTest.SHA512SimpleFile [ OK ] ShasumTest.SHA512SimpleFile (100 ms) [----------] 1 test from ShasumTest (100 ms total) [----------] 1 test from CompressionTest [ RUN ] CompressionTest.GZIPDecompressTarFile [ OK ] CompressionTest.GZIPDecompressTarFile (304 ms) [----------] 1 test from CompressionTest (304 ms total) [----------] 3 tests from AppcSpecTest [ RUN ] AppcSpecTest.ValidateImageManifest [ OK ] AppcSpecTest.ValidateImageManifest (1 ms) [ RUN ] AppcSpecTest.ValidateLayout [ OK ] AppcSpecTest.ValidateLayout (1 ms) [ RUN ] AppcSpecTest.ValidateImageManifestWithNullExec [ OK ] AppcSpecTest.ValidateImageManifestWithNullExec (1 ms) [----------] 3 tests from AppcSpecTest (3 ms total) [----------] 5 tests from ComposingContainerizerTest [ RUN ] ComposingContainerizerTest.DestroyDuringUnsupportedLaunchLoop [ OK ] ComposingContainerizerTest.DestroyDuringUnsupportedLaunchLoop = (2 ms) [ RUN ] ComposingContainerizerTest.DestroyDuringSupportedLaunchLoop [ OK ] ComposingContainerizerTest.DestroyDuringSupportedLaunchLoop (1= ms) [ RUN ] ComposingContainerizerTest.DestroyAfterLaunchLoop [ OK ] ComposingContainerizerTest.DestroyAfterLaunchLoop (1 ms) [ RUN ] ComposingContainerizerTest.DestroyUnknownContainer W0204 05:19:48.690151 24822 composing.cpp:630] Attempted to destroy unknown= container 64c69b66-1728-473e-aff4-b1e9a8918323 [ OK ] ComposingContainerizerTest.DestroyUnknownContainer (1 ms) [ RUN ] ComposingContainerizerTest.WaitUnknownContainer [ OK ] ComposingContainerizerTest.WaitUnknownContainer (1 ms) [----------] 5 tests from ComposingContainerizerTest (6 ms total) [----------] 10 tests from DockerSpecTest [ RUN ] DockerSpecTest.ParseImageReference [ OK ] DockerSpecTest.ParseImageReference (0 ms) [ RUN ] DockerSpecTest.GetRegistrySpec [ OK ] DockerSpecTest.GetRegistrySpec (0 ms) [ RUN ] DockerSpecTest.ParseDockerConfig [ OK ] DockerSpecTest.ParseDockerConfig (0 ms) [ RUN ] DockerSpecTest.ParseDockercfg [ OK ] DockerSpecTest.ParseDockercfg (1 ms) [ RUN ] DockerSpecTest.ParseAuthUrl [ OK ] DockerSpecTest.ParseAuthUrl (0 ms) [ RUN ] DockerSpecTest.ParseV1ImageManifest [ OK ] DockerSpecTest.ParseV1ImageManifest (0 ms) [ RUN ] DockerSpecTest.ParseV2ImageManifest [ OK ] DockerSpecTest.ParseV2ImageManifest (1 ms) [ RUN ] DockerSpecTest.ParseInvalidV2ImageManifest [ OK ] DockerSpecTest.ParseInvalidV2ImageManifest (0 ms) [ RUN ] DockerSpecTest.ValidateV2ImageManifestFsLayersNonEmpty [ OK ] DockerSpecTest.ValidateV2ImageManifestFsLayersNonEmpty (0 ms) [ RUN ] DockerSpecTest.ValidateV2ImageManifestSignaturesNonEmpty [ OK ] DockerSpecTest.ValidateV2ImageManifestSignaturesNonEmpty (0 ms= ) [----------] 10 tests from DockerSpecTest (2 ms total) [----------] 1 test from DockerImageTest [ RUN ] DockerImageTest.ParseInspectonImage [ OK ] DockerImageTest.ParseInspectonImage (1 ms) [----------] 1 test from DockerImageTest (1 ms total) [----------] 4 tests from IOSwitchboardServerTest [ RUN ] IOSwitchboardServerTest.RedirectLog [ OK ] IOSwitchboardServerTest.RedirectLog (25 ms) [ RUN ] IOSwitchboardServerTest.AttachOutput [ OK ] IOSwitchboardServerTest.AttachOutput (477 ms) [ RUN ] IOSwitchboardServerTest.SendHeartbeat I0204 05:19:49.207033 24829 poll_socket.cpp:209] Socket error while sending= : Broken pipe [ OK ] IOSwitchboardServerTest.SendHeartbeat (11 ms) [ RUN ] IOSwitchboardServerTest.ReceiveHeartbeat [ OK ] IOSwitchboardServerTest.ReceiveHeartbeat (4 ms) [----------] 4 tests from IOSwitchboardServerTest (517 ms total) [----------] 5 tests from IOSwitchboardTest [ RUN ] IOSwitchboardTest.ContainerAttach I0204 05:19:49.212841 24812 containerizer.cpp:220] Using isolation: posix/c= pu,filesystem/posix,network/cni W0204 05:19:49.213150 24812 backend.cpp:76] Failed to create 'aufs' backend= : AufsBackend requires root privileges W0204 05:19:49.213199 24812 backend.cpp:76] Failed to create 'bind' backend= : BindBackend requires root privileges I0204 05:19:49.213222 24812 provisioner.cpp:249] Using default backend 'cop= y' I0204 05:19:49.214174 24815 containerizer.cpp:599] Recovering containerizer I0204 05:19:49.214639 24813 provisioner.cpp:410] Provisioner recovery compl= ete I0204 05:19:49.215313 24820 containerizer.cpp:992] Starting container 58ead= d2e-16a6-4c87-a6ef-beb8e579a7db for executor 'executor' of framework=20 I0204 05:19:49.216661 24824 switchboard.cpp:434] Allocated pseudo terminal = '/dev/pts/0' for container 58eadd2e-16a6-4c87-a6ef-beb8e579a7db I0204 05:19:49.216940 24824 switchboard.cpp:571] Launching 'mesos-io-switch= board' with flags '--heartbeat_interval=3D"30secs" --help=3D"false" --socke= t_address=3D"/tmp/mesos-io-switchboard-80dafc26-3c09-43b4-9aa3-e412063e72c7= " --stderr_from_fd=3D"6" --stderr_to_fd=3D"2" --stdin_to_fd=3D"6" --stdout_= from_fd=3D"6" --stdout_to_fd=3D"1" --tty=3D"true" --wait_for_connection=3D"= false"' for container 58eadd2e-16a6-4c87-a6ef-beb8e579a7db I0204 05:19:49.218948 24824 switchboard.cpp:601] Created I/O switchboard se= rver (pid: 28622) listening on socket file '/tmp/mesos-io-switchboard-80daf= c26-3c09-43b4-9aa3-e412063e72c7' for container 58eadd2e-16a6-4c87-a6ef-beb8= e579a7db I0204 05:19:49.220765 24827 containerizer.cpp:1541] Launching 'mesos-contai= nerizer' with flags '--help=3D"false" --launch_info=3D"{"command":{"shell":= true,"value":"sleep 1000"},"environment":{"variables":[{"name":"MESOS_SANDB= OX","value":"\/tmp\/IOSwitchboardTest_ContainerAttach_bFKIru"}]},"err":{"fd= ":7,"type":"FD"},"in":{"fd":7,"type":"FD"},"out":{"fd":7,"type":"FD"},"tty_= slave_path":"\/dev\/pts\/0","working_directory":"\/tmp\/IOSwitchboardTest_C= ontainerAttach_bFKIru"}" --pipe_read=3D"6" --pipe_write=3D"8" --runtime_dir= ectory=3D"/tmp/IOSwitchboardTest_ContainerAttach_TJk22Z/containers/58eadd2e= -16a6-4c87-a6ef-beb8e579a7db" --unshare_namespace_mnt=3D"false"' I0204 05:19:49.222266 24827 launcher.cpp:135] Forked child with pid '28623'= for container '58eadd2e-16a6-4c87-a6ef-beb8e579a7db' I0204 05:19:49.222604 24827 containerizer.cpp:1640] Checkpointing container= 's forked pid 28623 to '/tmp/IOSwitchboardTest_ContainerAttach_lrhOEv/meta/= slaves/frameworks/executors/executor/runs/58eadd2e-16a6-4c87-a6ef-beb8e579a= 7db/pids/forked.pid' I0204 05:19:49.224968 24819 fetcher.cpp:349] Starting to fetch URIs for con= tainer: 58eadd2e-16a6-4c87-a6ef-beb8e579a7db, directory: /tmp/IOSwitchboard= Test_ContainerAttach_bFKIru **** DEADLOCK DETECTED! **** You are waiting on process __limiter__(533)@172.17.0.2:54543 that it is cur= rently executing. I0204 05:19:49.279525 24819 containerizer.cpp:2119] Destroying container 58= eadd2e-16a6-4c87-a6ef-beb8e579a7db in RUNNING state I0204 05:19:49.279608 24819 launcher.cpp:151] Asked to destroy container 58= eadd2e-16a6-4c87-a6ef-beb8e579a7db E0204 05:19:49.334846 24813 switchboard.cpp:889] Unexpected termination of = I/O switchboard server: 'IOSwitchboard' exited with status 1 for container = 58eadd2e-16a6-4c87-a6ef-beb8e579a7db I0204 05:19:49.335072 24825 containerizer.cpp:2482] Container 58eadd2e-16a6= -4c87-a6ef-beb8e579a7db has exited I0204 05:19:49.336232 24817 provisioner.cpp:484] Ignoring destroy request f= or unknown container 58eadd2e-16a6-4c87-a6ef-beb8e579a7db [ OK ] IOSwitchboardTest.ContainerAttach (128 ms) [ RUN ] IOSwitchboardTest.OutputRedirectionWithTTY I0204 05:19:49.342509 24812 containerizer.cpp:220] Using isolation: posix/c= pu,filesystem/posix,network/cni W0204 05:19:49.342888 24812 backend.cpp:76] Failed to create 'aufs' backend= : AufsBackend requires root privileges W0204 05:19:49.342955 24812 backend.cpp:76] Failed to create 'bind' backend= : BindBackend requires root privileges I0204 05:19:49.342980 24812 provisioner.cpp:249] Using default backend 'cop= y' I0204 05:19:49.343853 24818 containerizer.cpp:599] Recovering containerizer I0204 05:19:49.344339 24821 provisioner.cpp:410] Provisioner recovery compl= ete I0204 05:19:49.344894 24823 containerizer.cpp:992] Starting container 0291b= 0bc-34b8-481e-8421-afb74c1f47ab for executor 'executor' of framework=20 I0204 05:19:49.345818 24820 switchboard.cpp:434] Allocated pseudo terminal = '/dev/pts/0' for container 0291b0bc-34b8-481e-8421-afb74c1f47ab I0204 05:19:49.346012 24820 switchboard.cpp:571] Launching 'mesos-io-switch= board' with flags '--heartbeat_interval=3D"30secs" --help=3D"false" --socke= t_address=3D"/tmp/mesos-io-switchboard-323f5702-87af-4f77-8580-201394e8aab3= " --stderr_from_fd=3D"6" --stderr_to_fd=3D"2" --stdin_to_fd=3D"6" --stdout_= from_fd=3D"6" --stdout_to_fd=3D"1" --tty=3D"true" --wait_for_connection=3D"= false"' for container 0291b0bc-34b8-481e-8421-afb74c1f47ab I0204 05:19:49.347755 24820 switchboard.cpp:601] Created I/O switchboard se= rver (pid: 28635) listening on socket file '/tmp/mesos-io-switchboard-323f5= 702-87af-4f77-8580-201394e8aab3' for container 0291b0bc-34b8-481e-8421-afb7= 4c1f47ab I0204 05:19:49.349141 24822 containerizer.cpp:1541] Launching 'mesos-contai= nerizer' with flags '--help=3D"false" --launch_info=3D"{"command":{"shell":= true,"value":"printf Hello; printf World 1>&2"},"environment":{"variables":= [{"name":"MESOS_SANDBOX","value":"\/tmp\/IOSwitchboardTest_OutputRedirectio= nWithTTY_M1gJVp"}]},"err":{"fd":7,"type":"FD"},"in":{"fd":7,"type":"FD"},"o= ut":{"fd":7,"type":"FD"},"tty_slave_path":"\/dev\/pts\/0","working_director= y":"\/tmp\/IOSwitchboardTest_OutputRedirectionWithTTY_M1gJVp"}" --pipe_read= =3D"6" --pipe_write=3D"8" --runtime_directory=3D"/tmp/IOSwitchboardTest_Out= putRedirectionWithTTY_8we0bV/containers/0291b0bc-34b8-481e-8421-afb74c1f47a= b" --unshare_namespace_mnt=3D"false"' I0204 05:19:49.350838 24822 launcher.cpp:135] Forked child with pid '28636'= for container '0291b0bc-34b8-481e-8421-afb74c1f47ab' I0204 05:19:49.351117 24822 containerizer.cpp:1640] Checkpointing container= 's forked pid 28636 to '/tmp/IOSwitchboardTest_OutputRedirectionWithTTY_OCb= Syr/meta/slaves/frameworks/executors/executor/runs/0291b0bc-34b8-481e-8421-= afb74c1f47ab/pids/forked.pid' I0204 05:19:49.353215 24823 fetcher.cpp:349] Starting to fetch URIs for con= tainer: 0291b0bc-34b8-481e-8421-afb74c1f47ab, directory: /tmp/IOSwitchboard= Test_OutputRedirectionWithTTY_M1gJVp E0204 05:19:49.435539 24818 switchboard.cpp:889] Unexpected termination of = I/O switchboard server: 'IOSwitchboard' exited with status 1 for container = 0291b0bc-34b8-481e-8421-afb74c1f47ab I0204 05:19:49.435703 24820 containerizer.cpp:2499] Container 0291b0bc-34b8= -481e-8421-afb74c1f47ab has reached its limit for resource {} and will be t= erminated I0204 05:19:49.435726 24820 containerizer.cpp:2119] Destroying container 02= 91b0bc-34b8-481e-8421-afb74c1f47ab in RUNNING state I0204 05:19:49.435780 24820 containerizer.cpp:2482] Container 0291b0bc-34b8= -481e-8421-afb74c1f47ab has exited I0204 05:19:49.435811 24820 launcher.cpp:151] Asked to destroy container 02= 91b0bc-34b8-481e-8421-afb74c1f47ab I0204 05:19:49.438181 24820 provisioner.cpp:484] Ignoring destroy request f= or unknown container 0291b0bc-34b8-481e-8421-afb74c1f47ab [ OK ] IOSwitchboardTest.OutputRedirectionWithTTY (100 ms) [ RUN ] IOSwitchboardTest.KillSwitchboardContainerDestroyed I0204 05:19:49.444211 24812 containerizer.cpp:220] Using isolation: posix/c= pu,filesystem/posix,network/cni W0204 05:19:49.444667 24812 backend.cpp:76] Failed to create 'aufs' backend= : AufsBackend requires root privileges W0204 05:19:49.444761 24812 backend.cpp:76] Failed to create 'bind' backend= : BindBackend requires root privileges I0204 05:19:49.444792 24812 provisioner.cpp:249] Using default backend 'cop= y' I0204 05:19:49.445595 24828 containerizer.cpp:599] Recovering containerizer I0204 05:19:49.446076 24821 provisioner.cpp:410] Provisioner recovery compl= ete I0204 05:19:49.446673 24817 containerizer.cpp:992] Starting container 15dd7= 219-f919-4ed0-9570-96b316afda9d for executor 'executor' of framework=20 I0204 05:19:49.447729 24815 containerizer.cpp:1541] Launching 'mesos-contai= nerizer' with flags '--help=3D"false" --launch_info=3D"{"command":{"shell":= true,"value":"sleep 1000"},"environment":{"variables":[{"name":"MESOS_SANDB= OX","value":"\/tmp\/IOSwitchboardTest_KillSwitchboardContainerDestroyed_IW2= yVo"}]},"err":{"path":"\/tmp\/IOSwitchboardTest_KillSwitchboardContainerDes= troyed_IW2yVo\/stderr","type":"PATH"},"out":{"path":"\/tmp\/IOSwitchboardTe= st_KillSwitchboardContainerDestroyed_IW2yVo\/stdout","type":"PATH"},"workin= g_directory":"\/tmp\/IOSwitchboardTest_KillSwitchboardContainerDestroyed_IW= 2yVo"}" --pipe_read=3D"6" --pipe_write=3D"7" --runtime_directory=3D"/tmp/IO= SwitchboardTest_KillSwitchboardContainerDestroyed_CdViVT/containers/15dd721= 9-f919-4ed0-9570-96b316afda9d" --unshare_namespace_mnt=3D"false"' I0204 05:19:49.449542 24815 launcher.cpp:135] Forked child with pid '28647'= for container '15dd7219-f919-4ed0-9570-96b316afda9d' I0204 05:19:49.449797 24815 containerizer.cpp:1640] Checkpointing container= 's forked pid 28647 to '/tmp/IOSwitchboardTest_KillSwitchboardContainerDest= royed_yXhuVo/meta/slaves/frameworks/executors/executor/runs/15dd7219-f919-4= ed0-9570-96b316afda9d/pids/forked.pid' I0204 05:19:49.451436 24813 fetcher.cpp:349] Starting to fetch URIs for con= tainer: 15dd7219-f919-4ed0-9570-96b316afda9d, directory: /tmp/IOSwitchboard= Test_KillSwitchboardContainerDestroyed_IW2yVo I0204 05:19:49.453418 24826 containerizer.cpp:1808] Starting nested contain= er 15dd7219-f919-4ed0-9570-96b316afda9d.a39a399b-b47e-4b68-aa1c-3c1df9aad6d= 1 I0204 05:19:49.454562 24825 switchboard.cpp:571] Launching 'mesos-io-switch= board' with flags '--heartbeat_interval=3D"30secs" --help=3D"false" --socke= t_address=3D"/tmp/mesos-io-switchboard-8808e33d-fccc-48cb-8c70-88fa62ccac16= " --stderr_from_fd=3D"10" --stderr_to_fd=3D"2" --stdin_to_fd=3D"7" --stdout= _from_fd=3D"8" --stdout_to_fd=3D"1" --tty=3D"false" --wait_for_connection= =3D"true"' for container 15dd7219-f919-4ed0-9570-96b316afda9d.a39a399b-b47e= -4b68-aa1c-3c1df9aad6d1 I0204 05:19:49.455788 24825 switchboard.cpp:601] Created I/O switchboard se= rver (pid: 28648) listening on socket file '/tmp/mesos-io-switchboard-8808e= 33d-fccc-48cb-8c70-88fa62ccac16' for container 15dd7219-f919-4ed0-9570-96b3= 16afda9d.a39a399b-b47e-4b68-aa1c-3c1df9aad6d1 I0204 05:19:49.456982 24815 containerizer.cpp:1541] Launching 'mesos-contai= nerizer' with flags '--help=3D"false" --launch_info=3D"{"command":{"shell":= true,"value":"sleep 1000"},"environment":{},"err":{"fd":11,"type":"FD"},"in= ":{"fd":6,"type":"FD"},"out":{"fd":9,"type":"FD"}}" --pipe_read=3D"7" --pip= e_write=3D"8" --runtime_directory=3D"/tmp/IOSwitchboardTest_KillSwitchboard= ContainerDestroyed_CdViVT/containers/15dd7219-f919-4ed0-9570-96b316afda9d/c= ontainers/a39a399b-b47e-4b68-aa1c-3c1df9aad6d1" --unshare_namespace_mnt=3D"= false"' I0204 05:19:49.458289 24815 launcher.cpp:135] Forked child with pid '28649'= for container '15dd7219-f919-4ed0-9570-96b316afda9d.a39a399b-b47e-4b68-aa1= c-3c1df9aad6d1' I0204 05:19:49.460134 24828 fetcher.cpp:349] Starting to fetch URIs for con= tainer: 15dd7219-f919-4ed0-9570-96b316afda9d.a39a399b-b47e-4b68-aa1c-3c1df9= aad6d1, directory: /tmp/IOSwitchboardTest_KillSwitchboardContainerDestroyed= _IW2yVo/containers/a39a399b-b47e-4b68-aa1c-3c1df9aad6d1 E0204 05:19:49.536142 24820 switchboard.cpp:889] Unexpected termination of = I/O switchboard server: 'IOSwitchboard' terminated with signal Killed for c= ontainer 15dd7219-f919-4ed0-9570-96b316afda9d.a39a399b-b47e-4b68-aa1c-3c1df= 9aad6d1 I0204 05:19:49.536379 24813 containerizer.cpp:2499] Container 15dd7219-f919= -4ed0-9570-96b316afda9d.a39a399b-b47e-4b68-aa1c-3c1df9aad6d1 has reached it= s limit for resource {} and will be terminated I0204 05:19:49.536409 24813 containerizer.cpp:2119] Destroying container 15= dd7219-f919-4ed0-9570-96b316afda9d.a39a399b-b47e-4b68-aa1c-3c1df9aad6d1 in = RUNNING state I0204 05:19:49.536478 24813 launcher.cpp:151] Asked to destroy container 15= dd7219-f919-4ed0-9570-96b316afda9d.a39a399b-b47e-4b68-aa1c-3c1df9aad6d1 I0204 05:19:49.637713 24814 containerizer.cpp:2482] Container 15dd7219-f919= -4ed0-9570-96b316afda9d.a39a399b-b47e-4b68-aa1c-3c1df9aad6d1 has exited E0204 05:19:49.638311 24825 switchboard.cpp:819] Failed to remove unix doma= in socket file '/tmp/mesos-io-switchboard-8808e33d-fccc-48cb-8c70-88fa62cca= c16' for container '15dd7219-f919-4ed0-9570-96b316afda9d.a39a399b-b47e-4b68= -aa1c-3c1df9aad6d1': No such file or directory I0204 05:19:49.638877 24813 provisioner.cpp:484] Ignoring destroy request f= or unknown container 15dd7219-f919-4ed0-9570-96b316afda9d.a39a399b-b47e-4b6= 8-aa1c-3c1df9aad6d1 I0204 05:19:49.639010 24826 containerizer.cpp:2398] Checkpointing terminati= on state to nested container's runtime directory '/tmp/IOSwitchboardTest_Ki= llSwitchboardContainerDestroyed_CdViVT/containers/15dd7219-f919-4ed0-9570-9= 6b316afda9d/containers/a39a399b-b47e-4b68-aa1c-3c1df9aad6d1/termination' I0204 05:19:49.639943 24826 containerizer.cpp:2119] Destroying container 15= dd7219-f919-4ed0-9570-96b316afda9d in RUNNING state I0204 05:19:49.640030 24826 launcher.cpp:151] Asked to destroy container 15= dd7219-f919-4ed0-9570-96b316afda9d I0204 05:19:49.738260 24818 containerizer.cpp:2482] Container 15dd7219-f919= -4ed0-9570-96b316afda9d has exited I0204 05:19:49.739009 24814 provisioner.cpp:484] Ignoring destroy request f= or unknown container 15dd7219-f919-4ed0-9570-96b316afda9d [ OK ] IOSwitchboardTest.KillSwitchboardContainerDestroyed (300 ms) [ RUN ] IOSwitchboardTest.RecoverThenKillSwitchboardContainerDestroyed I0204 05:19:49.745259 24812 cluster.cpp:160] Creating default 'local' autho= rizer I0204 05:19:49.746614 24820 master.cpp:383] Master 3926433f-91e2-4d0a-8120-= 74cb9cbeaf9d (dfb4d7994de8) started on 172.17.0.2:54543 I0204 05:19:49.746685 24820 master.cpp:385] Flags at startup: --acls=3D"" -= -agent_ping_timeout=3D"15secs" --agent_reregister_timeout=3D"10mins" --allo= cation_interval=3D"1secs" --allocator=3D"HierarchicalDRF" --authenticate_ag= ents=3D"true" --authenticate_frameworks=3D"true" --authenticate_http_framew= orks=3D"true" --authenticate_http_readonly=3D"true" --authenticate_http_rea= dwrite=3D"true" --authenticators=3D"crammd5" --authorizers=3D"local" --cred= entials=3D"/tmp/Y2wSmq/credentials" --framework_sorter=3D"drf" --help=3D"fa= lse" --hostname_lookup=3D"true" --http_authenticators=3D"basic" --http_fram= ework_authenticators=3D"basic" --initialize_driver_logging=3D"true" --log_a= uto_initialize=3D"true" --logbufsecs=3D"0" --logging_level=3D"INFO" --max_a= gent_ping_timeouts=3D"5" --max_completed_frameworks=3D"50" --max_completed_= tasks_per_framework=3D"1000" --max_unreachable_tasks_per_framework=3D"1000"= --quiet=3D"false" --recovery_agent_removal_limit=3D"100%" --registry=3D"in= _memory" --registry_fetch_timeout=3D"1mins" --registry_gc_interval=3D"15min= s" --registry_max_agent_age=3D"2weeks" --registry_max_agent_count=3D"102400= " --registry_store_timeout=3D"100secs" --registry_strict=3D"false" --root_s= ubmissions=3D"true" --user_sorter=3D"drf" --version=3D"false" --webui_dir= =3D"/usr/local/share/mesos/webui" --work_dir=3D"/tmp/Y2wSmq/master" --zk_se= ssion_timeout=3D"10secs" I0204 05:19:49.747035 24820 master.cpp:435] Master only allowing authentica= ted frameworks to register I0204 05:19:49.747046 24820 master.cpp:449] Master only allowing authentica= ted agents to register I0204 05:19:49.747054 24820 master.cpp:462] Master only allowing authentica= ted HTTP frameworks to register I0204 05:19:49.747063 24820 credentials.hpp:37] Loading credentials for aut= hentication from '/tmp/Y2wSmq/credentials' I0204 05:19:49.747283 24820 master.cpp:507] Using default 'crammd5' authent= icator I0204 05:19:49.747354 24820 http.cpp:919] Using default 'basic' HTTP authen= ticator for realm 'mesos-master-readonly' I0204 05:19:49.747416 24820 http.cpp:919] Using default 'basic' HTTP authen= ticator for realm 'mesos-master-readwrite' I0204 05:19:49.747503 24820 http.cpp:919] Using default 'basic' HTTP authen= ticator for realm 'mesos-master-scheduler' I0204 05:19:49.747627 24820 master.cpp:587] Authorization enabled I0204 05:19:49.747853 24819 whitelist_watcher.cpp:77] No whitelist given I0204 05:19:49.747890 24816 hierarchical.cpp:161] Initialized hierarchical = allocator process I0204 05:19:49.749389 24820 master.cpp:2123] Elected as the leading master! I0204 05:19:49.749413 24820 master.cpp:1645] Recovering from registrar I0204 05:19:49.749516 24815 registrar.cpp:329] Recovering registrar I0204 05:19:49.749909 24817 registrar.cpp:362] Successfully fetched the reg= istry (0B) in 358144ns I0204 05:19:49.749966 24817 registrar.cpp:461] Applied 1 operations in 1514= 7ns; attempting to update the registry I0204 05:19:49.750361 24826 registrar.cpp:506] Successfully updated the reg= istry in 354048ns I0204 05:19:49.750435 24826 registrar.cpp:392] Successfully recovered regis= trar I0204 05:19:49.750613 24821 master.cpp:1761] Recovered 0 agents from the re= gistry (129B); allowing 10mins for agents to re-register I0204 05:19:49.750710 24814 hierarchical.cpp:188] Skipping recovery of hier= archical allocator: nothing to recover Build timed out (after 300 minutes). Marking the build as failed. Build was aborted ++ docker rmi mesos-1486183071-27494 Error response from daemon: conflict: unable to remove repository reference= "mesos-1486183071-27494" (must force) - container dfb4d7994de8 is using it= s referenced image fd7eb002d659