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/02/02 06:49:18 UTC

Build failed in Jenkins: Mesos-Reviewbot #16949

See <https://builds.apache.org/job/Mesos-Reviewbot/16949/>

------------------------------------------
[...truncated 322556 lines...]
I0202 05:36:15.707370 30192 master.cpp:1263] Agent 4948bf33-4c24-4944-858c-22c1945c2f64-S0 at slave(521)@172.17.0.2:56694 (2075193ac54d) disconnected
I0202 05:36:15.707425 30192 master.cpp:3117] Disconnecting agent 4948bf33-4c24-4944-858c-22c1945c2f64-S0 at slave(521)@172.17.0.2:56694 (2075193ac54d)
I0202 05:36:15.707535 30192 master.cpp:3136] Deactivating agent 4948bf33-4c24-4944-858c-22c1945c2f64-S0 at slave(521)@172.17.0.2:56694 (2075193ac54d)
I0202 05:36:15.707870 30170 containerizer.cpp:220] Using isolation: posix/cpu,filesystem/posix,network/cni
I0202 05:36:15.707936 30192 hierarchical.cpp:579] Agent 4948bf33-4c24-4944-858c-22c1945c2f64-S0 deactivated
W0202 05:36:15.708829 30170 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0202 05:36:15.709111 30170 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0202 05:36:15.709264 30170 provisioner.cpp:249] Using default backend 'copy'
I0202 05:36:15.719261 30170 cluster.cpp:446] Creating default 'local' authorizer
I0202 05:36:15.723546 30201 slave.cpp:209] Mesos agent started on (522)@172.17.0.2:56694
I0202 05:36:15.724036 30201 slave.cpp:210] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/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/IOSwitchboardTest_ContainerAttachAfterSlaveRestart_LpECnD/credential" --default_role="*" --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/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/IOSwitchboardTest_ContainerAttachAfterSlaveRestart_LpECnD/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/IOSwitchboardTest_ContainerAttachAfterSlaveRestart_LpECnD/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu" --launcher="posix" --launcher_dir="/mesos/mesos-1.2.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/IOSwitchboardTest_ContainerAttachAfterSlaveRestart_LpECnD" --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/IOSwitchboardTest_ContainerAttachAfterSlaveRestart_MVdCVY"
I0202 05:36:15.724958 30201 credentials.hpp:86] Loading credential for authentication from '/tmp/IOSwitchboardTest_ContainerAttachAfterSlaveRestart_LpECnD/credential'
I0202 05:36:15.725373 30201 slave.cpp:352] Agent using credential for: test-principal
I0202 05:36:15.725396 30201 credentials.hpp:37] Loading credentials for authentication from '/tmp/IOSwitchboardTest_ContainerAttachAfterSlaveRestart_LpECnD/http_credentials'
I0202 05:36:15.725697 30201 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0202 05:36:15.725862 30201 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0202 05:36:15.727183 30201 slave.cpp:539] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0202 05:36:15.727265 30201 slave.cpp:547] Agent attributes: [  ]
I0202 05:36:15.727285 30201 slave.cpp:552] Agent hostname: 2075193ac54d
I0202 05:36:15.729074 30202 state.cpp:60] Recovering state from '/tmp/IOSwitchboardTest_ContainerAttachAfterSlaveRestart_MVdCVY/meta'
I0202 05:36:15.729631 30202 state.cpp:703] No committed checkpointed resources found at '/tmp/IOSwitchboardTest_ContainerAttachAfterSlaveRestart_MVdCVY/meta/resources/resources.info'
I0202 05:36:15.732652 30201 status_update_manager.cpp:177] Pausing sending status updates
I0202 05:36:15.734908 30196 fetcher.cpp:90] Clearing fetcher cache
I0202 05:36:15.734987 30196 slave.cpp:5510] Recovering framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000
I0202 05:36:15.735381 30196 slave.cpp:6501] Recovering executor '0cc2307a-2394-4d47-835a-747760ea948b' of framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000
I0202 05:36:15.736572 30202 status_update_manager.cpp:203] Recovering status update manager
I0202 05:36:15.736855 30202 status_update_manager.cpp:211] Recovering executor '0cc2307a-2394-4d47-835a-747760ea948b' of framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000
I0202 05:36:15.737134 30202 status_update_manager.cpp:500] Creating StatusUpdate stream for task 0cc2307a-2394-4d47-835a-747760ea948b of framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000
I0202 05:36:15.738050 30202 status_update_manager.cpp:808] Replaying status update stream for task 0cc2307a-2394-4d47-835a-747760ea948b
I0202 05:36:15.739020 30196 containerizer.cpp:599] Recovering containerizer
I0202 05:36:15.739197 30196 containerizer.cpp:655] Recovering container c711c86b-b1de-4318-b130-3cf1781afdea for executor '0cc2307a-2394-4d47-835a-747760ea948b' of framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000
I0202 05:36:15.742296 30192 provisioner.cpp:410] Provisioner recovery complete
I0202 05:36:15.743348 30195 slave.cpp:5362] Sending reconnect request to executor '0cc2307a-2394-4d47-835a-747760ea948b' of framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000 at executor(1)@172.17.0.2:34693
I0202 05:36:15.746086 30170 sched.cpp:2021] Asked to stop the driver
I0202 05:36:15.746387 30199 sched.cpp:1203] Stopping framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000
I0202 05:36:15.746755 30189 master.cpp:7560] Processing TEARDOWN call for framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000 (default) at scheduler-6d1df776-0e6b-4e39-901b-2ae9ace4a685@172.17.0.2:56694
I0202 05:36:15.746790 30189 master.cpp:7572] Removing framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000 (default) at scheduler-6d1df776-0e6b-4e39-901b-2ae9ace4a685@172.17.0.2:56694
I0202 05:36:15.746860 30189 master.cpp:3080] Deactivating framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000 (default) at scheduler-6d1df776-0e6b-4e39-901b-2ae9ace4a685@172.17.0.2:56694
I0202 05:36:15.747087 30195 hierarchical.cpp:387] Deactivated framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000
I0202 05:36:15.747094 30189 master.cpp:8157] Updating the state of task 0cc2307a-2394-4d47-835a-747760ea948b of framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0202 05:36:15.748667 30189 master.cpp:8251] Removing task 0cc2307a-2394-4d47-835a-747760ea948b with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000 on agent 4948bf33-4c24-4944-858c-22c1945c2f64-S0 at slave(521)@172.17.0.2:56694 (2075193ac54d)
I0202 05:36:15.748944 30190 hierarchical.cpp:1013] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 4948bf33-4c24-4944-858c-22c1945c2f64-S0 from framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000
I0202 05:36:15.749171 30195 slave.cpp:3515] Re-registering executor '0cc2307a-2394-4d47-835a-747760ea948b' of framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000
I0202 05:36:15.750061 30189 hierarchical.cpp:338] Removed framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000
I0202 05:36:15.759696 30192 containerizer.cpp:2147] Destroying container c711c86b-b1de-4318-b130-3cf1781afdea in RUNNING state
I0202 05:36:15.760185 30192 launcher.cpp:151] Asked to destroy container c711c86b-b1de-4318-b130-3cf1781afdea
I0202 05:36:15.770789 30204 slave.cpp:4328] Got exited event for executor(1)@172.17.0.2:34693
W0202 05:36:15.771075 30204 slave.cpp:4333] Master disconnected! Waiting for a new master to be elected
E0202 05:36:15.832903 30191 switchboard.cpp:889] Unexpected termination of I/O switchboard server: 'IOSwitchboard' exited with status 1 for container c711c86b-b1de-4318-b130-3cf1781afdea
I0202 05:36:15.833220 30204 containerizer.cpp:2510] Container c711c86b-b1de-4318-b130-3cf1781afdea has exited
I0202 05:36:15.835427 30190 provisioner.cpp:484] Ignoring destroy request for unknown container c711c86b-b1de-4318-b130-3cf1781afdea
I0202 05:36:15.837239 30200 slave.cpp:4691] Executor '0cc2307a-2394-4d47-835a-747760ea948b' of framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000 terminated with signal Killed
I0202 05:36:15.837409 30200 slave.cpp:3756] Handling status update TASK_FAILED (UUID: f83f7599-b594-45d1-b207-0ad7703b005c) for task 0cc2307a-2394-4d47-835a-747760ea948b of framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000 from @0.0.0.0:0
W0202 05:36:15.838727 30194 containerizer.cpp:1962] Ignoring update for unknown container c711c86b-b1de-4318-b130-3cf1781afdea
I0202 05:36:15.839151 30194 status_update_manager.cpp:323] Received status update TASK_FAILED (UUID: f83f7599-b594-45d1-b207-0ad7703b005c) for task 0cc2307a-2394-4d47-835a-747760ea948b of framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000
I0202 05:36:15.839242 30194 status_update_manager.cpp:832] Checkpointing UPDATE for status update TASK_FAILED (UUID: f83f7599-b594-45d1-b207-0ad7703b005c) for task 0cc2307a-2394-4d47-835a-747760ea948b of framework 4948bf33-4c24-4944-858c-22c1945c2f64-0000
I0202 05:36:15.852190 30170 slave.cpp:801] Agent terminating
I0202 05:36:15.863431 30170 master.cpp:1102] Master terminating
I0202 05:36:15.865581 30191 hierarchical.cpp:512] Removed agent 4948bf33-4c24-4944-858c-22c1945c2f64-S0
[       OK ] IOSwitchboardTest.ContainerAttachAfterSlaveRestart (510 ms)
[----------] 5 tests from IOSwitchboardTest (6155 ms total)

[----------] 3 tests from MesosContainerizerTest
[ RUN      ] MesosContainerizerTest.Launch
I0202 05:36:15.876278 30170 containerizer.cpp:220] Using isolation: posix/cpu,filesystem/posix,network/cni
W0202 05:36:15.877115 30170 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0202 05:36:15.877382 30170 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0202 05:36:15.877430 30170 provisioner.cpp:249] Using default backend 'copy'
I0202 05:36:15.880528 30197 containerizer.cpp:599] Recovering containerizer
I0202 05:36:15.882064 30200 provisioner.cpp:410] Provisioner recovery complete
I0202 05:36:15.883478 30198 containerizer.cpp:992] Starting container 03dc1a66-ade7-4922-a3d2-596c61b6d963 for executor 'executor' of framework 
I0202 05:36:15.885876 30198 containerizer.cpp:1569] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"exit 42"},"environment":{"variables":[{"name":"MESOS_SANDBOX","value":"\/tmp\/MesosContainerizerTest_Launch_emrlyx"}]},"working_directory":"\/tmp\/MesosContainerizerTest_Launch_emrlyx"}" --pipe_read="20" --pipe_write="21" --runtime_directory="/tmp/MesosContainerizerTest_Launch_xosZLR/containers/03dc1a66-ade7-4922-a3d2-596c61b6d963" --unshare_namespace_mnt="false"'
I0202 05:36:15.888959 30198 launcher.cpp:135] Forked child with pid '9076' for container '03dc1a66-ade7-4922-a3d2-596c61b6d963'
I0202 05:36:15.889209 30198 containerizer.cpp:1668] Checkpointing container's forked pid 9076 to '/tmp/MesosContainerizerTest_Launch_PQEP0b/meta/slaves/frameworks/executors/executor/runs/03dc1a66-ade7-4922-a3d2-596c61b6d963/pids/forked.pid'
I0202 05:36:15.892799 30195 fetcher.cpp:349] Starting to fetch URIs for container: 03dc1a66-ade7-4922-a3d2-596c61b6d963, directory: /tmp/MesosContainerizerTest_Launch_emrlyx
I0202 05:36:16.034737 30199 containerizer.cpp:2510] Container 03dc1a66-ade7-4922-a3d2-596c61b6d963 has exited
I0202 05:36:16.035133 30199 containerizer.cpp:2147] Destroying container 03dc1a66-ade7-4922-a3d2-596c61b6d963 in RUNNING state
I0202 05:36:16.035495 30199 launcher.cpp:151] Asked to destroy container 03dc1a66-ade7-4922-a3d2-596c61b6d963
I0202 05:36:16.039453 30196 provisioner.cpp:484] Ignoring destroy request for unknown container 03dc1a66-ade7-4922-a3d2-596c61b6d963
[       OK ] MesosContainerizerTest.Launch (169 ms)
[ RUN      ] MesosContainerizerTest.Destroy
I0202 05:36:16.046773 30170 containerizer.cpp:220] Using isolation: posix/cpu,filesystem/posix,network/cni
W0202 05:36:16.047355 30170 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0202 05:36:16.047549 30170 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0202 05:36:16.047598 30170 provisioner.cpp:249] Using default backend 'copy'
I0202 05:36:16.049211 30202 containerizer.cpp:599] Recovering containerizer
I0202 05:36:16.050694 30194 provisioner.cpp:410] Provisioner recovery complete
I0202 05:36:16.051686 30194 containerizer.cpp:992] Starting container 58d17485-d4b7-431a-bb73-79283e3b9a6c for executor 'executor' of framework 
I0202 05:36:16.054113 30203 containerizer.cpp:1569] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"sleep 1000"},"environment":{"variables":[{"name":"MESOS_SANDBOX","value":"\/tmp\/MesosContainerizerTest_Destroy_z2udZB"}]},"working_directory":"\/tmp\/MesosContainerizerTest_Destroy_z2udZB"}" --pipe_read="20" --pipe_write="21" --runtime_directory="/tmp/MesosContainerizerTest_Destroy_kywwLV/containers/58d17485-d4b7-431a-bb73-79283e3b9a6c" --unshare_namespace_mnt="false"'
I0202 05:36:16.057620 30203 launcher.cpp:135] Forked child with pid '9091' for container '58d17485-d4b7-431a-bb73-79283e3b9a6c'
I0202 05:36:16.057868 30203 containerizer.cpp:1668] Checkpointing container's forked pid 9091 to '/tmp/MesosContainerizerTest_Destroy_Y1JFyf/meta/slaves/frameworks/executors/executor/runs/58d17485-d4b7-431a-bb73-79283e3b9a6c/pids/forked.pid'
I0202 05:36:16.061555 30202 fetcher.cpp:349] Starting to fetch URIs for container: 58d17485-d4b7-431a-bb73-79283e3b9a6c, directory: /tmp/MesosContainerizerTest_Destroy_z2udZB
I0202 05:36:16.065665 30189 containerizer.cpp:2147] Destroying container 58d17485-d4b7-431a-bb73-79283e3b9a6c in RUNNING state
I0202 05:36:16.065992 30189 launcher.cpp:151] Asked to destroy container 58d17485-d4b7-431a-bb73-79283e3b9a6c
I0202 05:36:16.135617 30189 containerizer.cpp:2510] Container 58d17485-d4b7-431a-bb73-79283e3b9a6c has exited
I0202 05:36:16.137610 30197 provisioner.cpp:484] Ignoring destroy request for unknown container 58d17485-d4b7-431a-bb73-79283e3b9a6c
[       OK ] MesosContainerizerTest.Destroy (107 ms)
[ RUN      ] MesosContainerizerTest.StatusWithContainerID
I0202 05:36:16.155400 30170 containerizer.cpp:220] Using isolation: posix/cpu,filesystem/posix,network/cni
W0202 05:36:16.156147 30170 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0202 05:36:16.156297 30170 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0202 05:36:16.156347 30170 provisioner.cpp:249] Using default backend 'copy'
I0202 05:36:16.158594 30194 containerizer.cpp:599] Recovering containerizer
I0202 05:36:16.160332 30199 provisioner.cpp:410] Provisioner recovery complete
I0202 05:36:16.161576 30199 containerizer.cpp:992] Starting container 3a605a58-c0b1-4b31-8ff2-7571faf9dc6b for executor 'executor' of framework 
I0202 05:36:16.164985 30198 containerizer.cpp:1569] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"sleep 1000"},"environment":{"variables":[{"name":"MESOS_SANDBOX","value":"\/tmp\/MesosContainerizerTest_StatusWithContainerID_VsCrrI"}]},"working_directory":"\/tmp\/MesosContainerizerTest_StatusWithContainerID_VsCrrI"}" --pipe_read="20" --pipe_write="21" --runtime_directory="/tmp/MesosContainerizerTest_StatusWithContainerID_59IUV1/containers/3a605a58-c0b1-4b31-8ff2-7571faf9dc6b" --unshare_namespace_mnt="false"'
I0202 05:36:16.169101 30198 launcher.cpp:135] Forked child with pid '9095' for container '3a605a58-c0b1-4b31-8ff2-7571faf9dc6b'
I0202 05:36:16.169361 30198 containerizer.cpp:1668] Checkpointing container's forked pid 9095 to '/tmp/MesosContainerizerTest_StatusWithContainerID_7kCsrl/meta/slaves/frameworks/executors/executor/runs/3a605a58-c0b1-4b31-8ff2-7571faf9dc6b/pids/forked.pid'
I0202 05:36:16.174521 30199 fetcher.cpp:349] Starting to fetch URIs for container: 3a605a58-c0b1-4b31-8ff2-7571faf9dc6b, directory: /tmp/MesosContainerizerTest_StatusWithContainerID_VsCrrI
I0202 05:36:16.179409 30199 containerizer.cpp:2147] Destroying container 3a605a58-c0b1-4b31-8ff2-7571faf9dc6b in RUNNING state
I0202 05:36:16.179733 30199 launcher.cpp:151] Asked to destroy container 3a605a58-c0b1-4b31-8ff2-7571faf9dc6b
I0202 05:36:16.236937 30199 containerizer.cpp:2510] Container 3a605a58-c0b1-4b31-8ff2-7571faf9dc6b has exited
I0202 05:36:16.239008 30198 provisioner.cpp:484] Ignoring destroy request for unknown container 3a605a58-c0b1-4b31-8ff2-7571faf9dc6b
[       OK ] MesosContainerizerTest.StatusWithContainerID (91 ms)
[----------] 3 tests from MesosContainerizerTest (370 ms total)

[----------] 4 tests from MesosContainerizerIsolatorPreparationTest
[ RUN      ] MesosContainerizerIsolatorPreparationTest.ScriptSucceeds
W0202 05:36:16.246742 30170 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0202 05:36:16.246868 30170 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0202 05:36:16.246914 30170 provisioner.cpp:249] Using default backend 'copy'
I0202 05:36:16.247845 30196 containerizer.cpp:992] Starting container 68067fb4-f59a-4fb6-9a55-9157f413738d for executor 'executor' of framework 
I0202 05:36:16.249703 30204 containerizer.cpp:1569] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"exit 0"},"environment":{"variables":[{"name":"MESOS_SANDBOX","value":"\/tmp\/UjKmeM"}]},"pre_exec_commands":[{"shell":true,"value":"touch \/tmp\/UjKmeM\/child.script.executed"}],"working_directory":"\/tmp\/UjKmeM"}" --pipe_read="20" --pipe_write="21" --runtime_directory="/tmp/MesosContainerizerIsolatorPreparationTest_ScriptSucceeds_heOYG9/containers/68067fb4-f59a-4fb6-9a55-9157f413738d" --unshare_namespace_mnt="false"'
I0202 05:36:16.253906 30204 launcher.cpp:135] Forked child with pid '9102' for container '68067fb4-f59a-4fb6-9a55-9157f413738d'
I0202 05:36:16.257841 30199 fetcher.cpp:349] Starting to fetch URIs for container: 68067fb4-f59a-4fb6-9a55-9157f413738d, directory: /tmp/UjKmeM
Executing pre-exec command '{"shell":true,"value":"touch \/tmp\/UjKmeM\/child.script.executed"}'
I0202 05:36:16.439651 30202 containerizer.cpp:2510] Container 68067fb4-f59a-4fb6-9a55-9157f413738d has exited
I0202 05:36:16.439713 30202 containerizer.cpp:2147] Destroying container 68067fb4-f59a-4fb6-9a55-9157f413738d in RUNNING state
I0202 05:36:16.440052 30202 launcher.cpp:151] Asked to destroy container 68067fb4-f59a-4fb6-9a55-9157f413738d
I0202 05:36:16.443043 30200 provisioner.cpp:484] Ignoring destroy request for unknown container 68067fb4-f59a-4fb6-9a55-9157f413738d
[       OK ] MesosContainerizerIsolatorPreparationTest.ScriptSucceeds (205 ms)
[ RUN      ] MesosContainerizerIsolatorPreparationTest.ScriptFails
W0202 05:36:16.452337 30170 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0202 05:36:16.452502 30170 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0202 05:36:16.452546 30170 provisioner.cpp:249] Using default backend 'copy'
I0202 05:36:16.454020 30201 containerizer.cpp:992] Starting container 1fc3c4af-163e-49de-844a-7569d5232b9d for executor 'executor' of framework 
I0202 05:36:16.455595 30201 containerizer.cpp:1569] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"exit 0"},"environment":{"variables":[{"name":"MESOS_SANDBOX","value":"\/tmp\/uVkLBw"}]},"pre_exec_commands":[{"shell":true,"value":"touch \/tmp\/uVkLBw\/child.script.executed && exit 1"}],"working_directory":"\/tmp\/uVkLBw"}" --pipe_read="20" --pipe_write="21" --runtime_directory="/tmp/MesosContainerizerIsolatorPreparationTest_ScriptFails_QFn98U/containers/1fc3c4af-163e-49de-844a-7569d5232b9d" --unshare_namespace_mnt="false"'
I0202 05:36:16.458477 30201 launcher.cpp:135] Forked child with pid '9119' for container '1fc3c4af-163e-49de-844a-7569d5232b9d'
I0202 05:36:16.461953 30200 fetcher.cpp:349] Starting to fetch URIs for container: 1fc3c4af-163e-49de-844a-7569d5232b9d, directory: /tmp/uVkLBw
Executing pre-exec command '{"shell":true,"value":"touch \/tmp\/uVkLBw\/child.script.executed && exit 1"}'
Failed to execute pre-exec command '{"shell":true,"value":"touch \/tmp\/uVkLBw\/child.script.executed && exit 1"}': exited with status 1
I0202 05:36:16.641335 30197 containerizer.cpp:2510] Container 1fc3c4af-163e-49de-844a-7569d5232b9d has exited
I0202 05:36:16.641396 30197 containerizer.cpp:2147] Destroying container 1fc3c4af-163e-49de-844a-7569d5232b9d in RUNNING state
I0202 05:36:16.641703 30197 launcher.cpp:151] Asked to destroy container 1fc3c4af-163e-49de-844a-7569d5232b9d
I0202 05:36:16.645279 30197 provisioner.cpp:484] Ignoring destroy request for unknown container 1fc3c4af-163e-49de-844a-7569d5232b9d
W0202 05:36:16.646231 30203 containerizer.cpp:2135] Attempted to destroy unknown container 1fc3c4af-163e-49de-844a-7569d5232b9d
[       OK ] MesosContainerizerIsolatorPreparationTest.ScriptFails (200 ms)
[ RUN      ] MesosContainerizerIsolatorPreparationTest.MultipleScripts
W0202 05:36:16.651443 30170 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0202 05:36:16.651553 30170 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0202 05:36:16.651656 30170 provisioner.cpp:249] Using default backend 'copy'
I0202 05:36:16.652642 30193 containerizer.cpp:992] Starting container 97b29065-d4a4-45d6-811a-b7615b815283 for executor 'executor' of framework 
I0202 05:36:16.654342 30203 containerizer.cpp:1569] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"exit 0"},"environment":{"variables":[{"name":"MESOS_SANDBOX","value":"\/tmp\/a33Jdk"}]},"pre_exec_commands":[{"shell":true,"value":"touch \/tmp\/a33Jdk\/child.script.executed.1 && exit 0"},{"shell":true,"value":"touch \/tmp\/a33Jdk\/child.script.executed.2 && exit 1"}],"working_directory":"\/tmp\/a33Jdk"}" --pipe_read="20" --pipe_write="21" --runtime_directory="/tmp/MesosContainerizerIsolatorPreparationTest_MultipleScripts_16oPNJ/containers/97b29065-d4a4-45d6-811a-b7615b815283" --unshare_namespace_mnt="false"'
I0202 05:36:16.657564 30203 launcher.cpp:135] Forked child with pid '9135' for container '97b29065-d4a4-45d6-811a-b7615b815283'
I0202 05:36:16.660265 30192 fetcher.cpp:349] Starting to fetch URIs for container: 97b29065-d4a4-45d6-811a-b7615b815283, directory: /tmp/a33Jdk
Executing pre-exec command '{"shell":true,"value":"touch \/tmp\/a33Jdk\/child.script.executed.1 && exit 0"}'
Executing pre-exec command '{"shell":true,"value":"touch \/tmp\/a33Jdk\/child.script.executed.2 && exit 1"}'
Failed to execute pre-exec command '{"shell":true,"value":"touch \/tmp\/a33Jdk\/child.script.executed.2 && exit 1"}': exited with status 1
I0202 05:36:16.842994 30202 containerizer.cpp:2510] Container 97b29065-d4a4-45d6-811a-b7615b815283 has exited
I0202 05:36:16.843046 30202 containerizer.cpp:2147] Destroying container 97b29065-d4a4-45d6-811a-b7615b815283 in RUNNING state
I0202 05:36:16.843324 30202 launcher.cpp:151] Asked to destroy container 97b29065-d4a4-45d6-811a-b7615b815283
I0202 05:36:16.846793 30198 provisioner.cpp:484] Ignoring destroy request for unknown container 97b29065-d4a4-45d6-811a-b7615b815283
W0202 05:36:16.847821 30198 containerizer.cpp:2135] Attempted to destroy unknown container 97b29065-d4a4-45d6-811a-b7615b815283
[       OK ] MesosContainerizerIsolatorPreparationTest.MultipleScripts (201 ms)
[ RUN      ] MesosContainerizerIsolatorPreparationTest.ExecutorEnvironmentVariable
W0202 05:36:16.852872 30170 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0202 05:36:16.852980 30170 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0202 05:36:16.853016 30170 provisioner.cpp:249] Using default backend 'copy'
I0202 05:36:16.855150 30194 containerizer.cpp:992] Starting container c8f3deb5-6ca5-427e-a1d2-09d82501c1e7 for executor 'executor' of framework 
I0202 05:36:16.856845 30194 containerizer.cpp:1569] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"if [ -n \"$LIBPROCESS_IP\" ]; then touch $TEST_ENVIRONMENT; fi"},"environment":{"variables":[{"name":"LIBPROCESS_IP","value":"127.0.0.1"},{"name":"LIBPROCESS_PORT","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","value":"0.0.0.0:0"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/tmp\/CKivV9"},{"name":"MESOS_EXECUTOR_ID","value":"executor"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":""},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"},{"name":"MESOS_SLAVE_ID","value":""},{"name":"MESOS_SLAVE_PID","value":"@0.0.0.0:0"},{"name":"MESOS_SANDBOX","value":"\/tmp\/CKivV9"},{"name":"TEST_ENVIRONMENT","value":"\/tmp\/CKivV9\/child.script.executed"}]},"working_directory":"\/tmp\/CKivV9"}" --pipe_read="20" --pipe_write="21" --runtime_directory="/tmp/MesosContainerizerIsolatorPreparationTest_ExecutorEnvironmentVariable_rWr1yA/containers/c8f3deb5-6ca5-427e-a1d2-09d82501c1e7" --unshare_namespace_mnt="false"'
I0202 05:36:16.859951 30194 launcher.cpp:135] Forked child with pid '9153' for container 'c8f3deb5-6ca5-427e-a1d2-09d82501c1e7'
I0202 05:36:16.862695 30196 fetcher.cpp:349] Starting to fetch URIs for container: c8f3deb5-6ca5-427e-a1d2-09d82501c1e7, directory: /tmp/CKivV9
I0202 05:36:17.044695 30196 containerizer.cpp:2510] Container c8f3deb5-6ca5-427e-a1d2-09d82501c1e7 has exited
I0202 05:36:17.044750 30196 containerizer.cpp:2147] Destroying container c8f3deb5-6ca5-427e-a1d2-09d82501c1e7 in RUNNING state
I0202 05:36:17.045047 30196 launcher.cpp:151] Asked to destroy container c8f3deb5-6ca5-427e-a1d2-09d82501c1e7
I0202 05:36:17.048614 30200 provisioner.cpp:484] Ignoring destroy request for unknown container c8f3deb5-6ca5-427e-a1d2-09d82501c1e7
W0202 05:36:17.049532 30200 containerizer.cpp:2135] Attempted to destroy unknown container c8f3deb5-6ca5-427e-a1d2-09d82501c1e7
[       OK ] MesosContainerizerIsolatorPreparationTest.ExecutorEnvironmentVariable (201 ms)
[----------] 4 tests from MesosContainerizerIsolatorPreparationTest (808 ms total)

[----------] 1 test from MesosContainerizerExecuteTest
[ RUN      ] MesosContainerizerExecuteTest.IoRedirection
I0202 05:36:17.054189 30170 containerizer.cpp:220] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0202 05:36:17.054713 30170 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0202 05:36:17.054834 30170 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0202 05:36:17.054878 30170 provisioner.cpp:249] Using default backend 'copy'
I0202 05:36:17.056785 30192 containerizer.cpp:992] Starting container 93a31c78-e401-48ec-97b2-61b2c5b3f952 for executor 'executor' of framework 
I0202 05:36:17.059429 30193 containerizer.cpp:1569] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"(echo 'this is stderr' 1>&2) && echo 'this is stdout'"},"environment":{"variables":[{"name":"MESOS_SANDBOX","value":"\/tmp\/7CplK1"}]},"err":{"path":"\/tmp\/7CplK1\/stderr","type":"PATH"},"out":{"path":"\/tmp\/7CplK1\/stdout","type":"PATH"},"working_directory":"\/tmp\/7CplK1"}" --pipe_read="20" --pipe_write="21" --runtime_directory="/tmp/MesosContainerizerExecuteTest_IoRedirection_YAOjrt/containers/93a31c78-e401-48ec-97b2-61b2c5b3f952" --unshare_namespace_mnt="false"'
I0202 05:36:17.062461 30193 launcher.cpp:135] Forked child with pid '9169' for container '93a31c78-e401-48ec-97b2-61b2c5b3f952'
I0202 05:36:17.065703 30199 fetcher.cpp:349] Starting to fetch URIs for container: 93a31c78-e401-48ec-97b2-61b2c5b3f952, directory: /tmp/7CplK1
I0202 05:36:17.246279 30189 containerizer.cpp:2510] Container 93a31c78-e401-48ec-97b2-61b2c5b3f952 has exited
I0202 05:36:17.246335 30189 containerizer.cpp:2147] Destroying container 93a31c78-e401-48ec-97b2-61b2c5b3f952 in RUNNING state
I0202 05:36:17.246628 30189 launcher.cpp:151] Asked to destroy container 93a31c78-e401-48ec-97b2-61b2c5b3f952
I0202 05:36:17.250857 30203 provisioner.cpp:484] Ignoring destroy request for unknown container 93a31c78-e401-48ec-97b2-61b2c5b3f952
[       OK ] MesosContainerizerExecuteTest.IoRedirection (203 ms)
[----------] 1 test from MesosContainerizerExecuteTest (204 ms total)

[----------] 4 tests from MesosContainerizerDestroyTest
[ RUN      ] MesosContainerizerDestroyTest.DestroyWhileFetching
W0202 05:36:17.258702 30170 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0202 05:36:17.258836 30170 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0202 05:36:17.258878 30170 provisioner.cpp:249] Using default backend 'copy'
I0202 05:36:17.260288 30201 containerizer.cpp:992] Starting container afcd6b09-6b43-4097-bb46-f3a190fc1364 for executor 'executor' of framework 
I0202 05:36:17.261821 30201 containerizer.cpp:1569] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"exit 0"},"environment":{"variables":[{"name":"MESOS_SANDBOX","value":"\/tmp\/jbLzGV"}]},"working_directory":"\/tmp\/jbLzGV"}" --pipe_read="20" --pipe_write="21" --runtime_directory="/tmp/MesosContainerizerDestroyTest_DestroyWhileFetching_zKZNro/containers/afcd6b09-6b43-4097-bb46-f3a190fc1364" --unshare_namespace_mnt="false"'
I0202 05:36:17.266127 30201 launcher.cpp:135] Forked child with pid '9185' for container 'afcd6b09-6b43-4097-bb46-f3a190fc1364'
I0202 05:36:17.269767 30203 fetcher.cpp:349] Starting to fetch URIs for container: afcd6b09-6b43-4097-bb46-f3a190fc1364, directory: /tmp/jbLzGV
I0202 05:36:17.270357 30203 containerizer.cpp:2147] Destroying container afcd6b09-6b43-4097-bb46-f3a190fc1364 in FETCHING state
I0202 05:36:17.270726 30203 launcher.cpp:151] Asked to destroy container afcd6b09-6b43-4097-bb46-f3a190fc1364
I0202 05:36:17.347764 30195 containerizer.cpp:2510] Container afcd6b09-6b43-4097-bb46-f3a190fc1364 has exited
I0202 05:36:17.349071 30197 provisioner.cpp:484] Ignoring destroy request for unknown container afcd6b09-6b43-4097-bb46-f3a190fc1364
[       OK ] MesosContainerizerDestroyTest.DestroyWhileFetching (103 ms)
[ RUN      ] MesosContainerizerDestroyTest.DestroyWhilePreparing
W0202 05:36:17.361820 30170 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0202 05:36:17.361945 30170 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0202 05:36:17.361991 30170 provisioner.cpp:249] Using default backend 'copy'
I0202 05:36:17.363103 30198 containerizer.cpp:992] Starting container f6ffce7c-7d59-4d46-926a-3b33f8b26bfe for executor 'executor' of framework 
I0202 05:36:17.364289 30198 containerizer.cpp:2147] Destroying container f6ffce7c-7d59-4d46-926a-3b33f8b26bfe in PREPARING state
I0202 05:36:17.364595 30198 containerizer.cpp:2218] Waiting for the isolators to complete preparing before destroying container f6ffce7c-7d59-4d46-926a-3b33f8b26bfe
I0202 05:36:17.367266 30201 provisioner.cpp:484] Ignoring destroy request for unknown container f6ffce7c-7d59-4d46-926a-3b33f8b26bfe
[       OK ] MesosContainerizerDestroyTest.DestroyWhilePreparing (11 ms)
[ RUN      ] MesosContainerizerDestroyTest.DestroyUnknownContainer
I0202 05:36:17.372508 30170 containerizer.cpp:220] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0202 05:36:17.373001 30170 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0202 05:36:17.373101 30170 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0202 05:36:17.373154 30170 provisioner.cpp:249] Using default backend 'copy'
W0202 05:36:17.375198 30196 containerizer.cpp:2135] Attempted to destroy unknown container ba0bdec6-fdb7-4545-805f-1e3c95644f25
[       OK ] MesosContainerizerDestroyTest.DestroyUnknownContainer (7 ms)
[ RUN      ] MesosContainerizerDestroyTest.LauncherDestroyFailure
W0202 05:36:17.380888 30170 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0202 05:36:17.381011 30170 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0202 05:36:17.381067 30170 provisioner.cpp:249] Using default backend 'copy'
I0202 05:36:17.382140 30199 containerizer.cpp:992] Starting container e741f2e4-3093-4236-914a-d25d538353b8 for executor 'executor' of framework 
I0202 05:36:17.383766 30199 containerizer.cpp:1569] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"sleep 1000"},"environment":{"variables":[{"name":"MESOS_SANDBOX","value":"\/tmp\/YZDhxh"}]},"working_directory":"\/tmp\/YZDhxh"}" --pipe_read="22" --pipe_write="24" --runtime_directory="/tmp/MesosContainerizerDestroyTest_LauncherDestroyFailure_k2ahWK/containers/e741f2e4-3093-4236-914a-d25d538353b8" --unshare_namespace_mnt="false"'
I0202 05:36:17.387763 30199 launcher.cpp:135] Forked child with pid '9186' for container 'e741f2e4-3093-4236-914a-d25d538353b8'
I0202 05:36:17.391140 30190 fetcher.cpp:349] Starting to fetch URIs for container: e741f2e4-3093-4236-914a-d25d538353b8, directory: /tmp/YZDhxh
I0202 05:36:17.394960 30193 containerizer.cpp:2147] Destroying container e741f2e4-3093-4236-914a-d25d538353b8 in RUNNING state
I0202 05:36:17.395308 30193 launcher.cpp:151] Asked to destroy container e741f2e4-3093-4236-914a-d25d538353b8
I0202 05:36:17.452747 30193 containerizer.cpp:2510] Container e741f2e4-3093-4236-914a-d25d538353b8 has exited
I0202 05:36:17.455996 30200 process.cpp:3697] Handling HTTP event for process 'metrics' with path: '/metrics/snapshot'
[       OK ] MesosContainerizerDestroyTest.LauncherDestroyFailure (86 ms)
[----------] 4 tests from MesosContainerizerDestroyTest (210 ms total)

[----------] 3 tests from MesosContainerizerProvisionerTest
[ RUN      ] MesosContainerizerProvisionerTest.ProvisionFailed
I0202 05:36:17.470171 30194 containerizer.cpp:992] Starting container 58a78761-5956-444f-82ec-85de92c4d4a1 for executor 'executor' of framework 
../../src/tests/containerizer/mesos_containerizer_tests.cpp:979: Failure
Failed to wait 15secs for wait
Build timed out (after 180 minutes). Marking the build as failed.
Build was aborted

Jenkins build is back to normal : Mesos-Reviewbot #16950

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/16950/>