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/08/15 15:13:45 UTC
Build failed in Jenkins: Mesos-Reviewbot #18878
See <https://builds.apache.org/job/Mesos-Reviewbot/18878/display/redirect>
------------------------------------------
[...truncated 8.09 MB...]
I0815 15:13:33.205622 5278 hierarchical.cpp:626] Removed agent 4317b1bb-0109-4830-b83c-59044bd8c82c-S0
[ OK ] GarbageCollectorIntegrationTest.ExitedExecutor (194 ms)
[ RUN ] GarbageCollectorIntegrationTest.DiskUsage
I0815 15:13:33.212262 5253 cluster.cpp:162] Creating default 'local' authorizer
I0815 15:13:33.217293 5277 master.cpp:442] Master 7d93de72-68b6-442c-a3c6-cfd986790f42 (f9b3044a9377) started on 172.17.0.2:38361
I0815 15:13:33.217337 5277 master.cpp:444] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/7HbKvB/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-1.4.0/_inst/share/mesos/webui" --work_dir="/tmp/7HbKvB/master" --zk_session_timeout="10secs"
I0815 15:13:33.218312 5277 master.cpp:494] Master only allowing authenticated frameworks to register
I0815 15:13:33.218343 5277 master.cpp:508] Master only allowing authenticated agents to register
I0815 15:13:33.218384 5277 master.cpp:521] Master only allowing authenticated HTTP frameworks to register
I0815 15:13:33.218663 5277 credentials.hpp:37] Loading credentials for authentication from '/tmp/7HbKvB/credentials'
I0815 15:13:33.219292 5277 master.cpp:566] Using default 'crammd5' authenticator
I0815 15:13:33.219730 5277 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0815 15:13:33.220124 5277 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0815 15:13:33.220683 5277 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0815 15:13:33.221071 5277 master.cpp:646] Authorization enabled
I0815 15:13:33.221575 5274 hierarchical.cpp:171] Initialized hierarchical allocator process
I0815 15:13:33.221606 5279 whitelist_watcher.cpp:77] No whitelist given
I0815 15:13:33.225466 5277 master.cpp:2163] Elected as the leading master!
I0815 15:13:33.225522 5277 master.cpp:1702] Recovering from registrar
I0815 15:13:33.225967 5273 registrar.cpp:347] Recovering registrar
I0815 15:13:33.226953 5273 registrar.cpp:391] Successfully fetched the registry (0B) in 928us
I0815 15:13:33.227318 5273 registrar.cpp:495] Applied 1 operations in 40482ns; attempting to update the registry
I0815 15:13:33.228595 5273 registrar.cpp:552] Successfully updated the registry in 1.195264ms
I0815 15:13:33.229029 5273 registrar.cpp:424] Successfully recovered registrar
I0815 15:13:33.229781 5273 master.cpp:1801] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0815 15:13:33.229825 5279 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
I0815 15:13:33.238492 5253 process.cpp:3228] Attempting to spawn already spawned process files@172.17.0.2:38361
I0815 15:13:33.239284 5253 cluster.cpp:448] Creating default 'local' authorizer
I0815 15:13:33.242558 5279 slave.cpp:250] Mesos agent started on (87)@172.17.0.2:38361
I0815 15:13:33.242599 5279 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/GarbageCollectorIntegrationTest_DiskUsage_DxoaYc/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/GarbageCollectorIntegrationTest_DiskUsage_DxoaYc/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/GarbageCollectorIntegrationTest_DiskUsage_DxoaYc/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/GarbageCollectorIntegrationTest_DiskUsage_DxoaYc/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/GarbageCollectorIntegrationTest_DiskUsage_DxoaYc/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_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="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/GarbageCollectorIntegrationTest_DiskUsage_DxoaYc" --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/GarbageCollectorIntegrationTest_DiskUsage_aHxWeU"
I0815 15:13:33.243350 5279 credentials.hpp:86] Loading credential for authentication from '/tmp/GarbageCollectorIntegrationTest_DiskUsage_DxoaYc/credential'
I0815 15:13:33.243599 5279 slave.cpp:283] Agent using credential for: test-principal
I0815 15:13:33.243624 5279 credentials.hpp:37] Loading credentials for authentication from '/tmp/GarbageCollectorIntegrationTest_DiskUsage_DxoaYc/http_credentials'
I0815 15:13:33.244019 5279 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0815 15:13:33.244302 5279 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0815 15:13:33.246489 5279 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0815 15:13:33.246918 5279 slave.cpp:573] Agent attributes: [ ]
I0815 15:13:33.246939 5279 slave.cpp:582] Agent hostname: f9b3044a9377
I0815 15:13:33.247254 5277 status_update_manager.cpp:177] Pausing sending status updates
I0815 15:13:33.249151 5274 state.cpp:64] Recovering state from '/tmp/GarbageCollectorIntegrationTest_DiskUsage_aHxWeU/meta'
I0815 15:13:33.249660 5275 status_update_manager.cpp:203] Recovering status update manager
I0815 15:13:33.250355 5279 slave.cpp:6210] Finished recovery
I0815 15:13:33.250960 5279 slave.cpp:6392] Querying resource estimator for oversubscribable resources
I0815 15:13:33.251425 5274 slave.cpp:971] New master detected at master@172.17.0.2:38361
I0815 15:13:33.251462 5275 status_update_manager.cpp:177] Pausing sending status updates
I0815 15:13:33.251704 5274 slave.cpp:1006] Detecting new master
I0815 15:13:33.252012 5274 slave.cpp:6406] Received oversubscribable resources {} from the resource estimator
I0815 15:13:33.256296 5273 slave.cpp:1033] Authenticating with master master@172.17.0.2:38361
I0815 15:13:33.256649 5273 slave.cpp:1044] Using default CRAM-MD5 authenticatee
I0815 15:13:33.257149 5279 authenticatee.cpp:121] Creating new client SASL connection
I0815 15:13:33.257596 5279 master.cpp:7837] Authenticating slave(87)@172.17.0.2:38361
I0815 15:13:33.257761 5274 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(194)@172.17.0.2:38361
I0815 15:13:33.258324 5275 authenticator.cpp:98] Creating new server SASL connection
I0815 15:13:33.258993 5275 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0815 15:13:33.259210 5275 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0815 15:13:33.259534 5275 authenticator.cpp:204] Received SASL authentication start
I0815 15:13:33.259732 5275 authenticator.cpp:326] Authentication requires more steps
I0815 15:13:33.260023 5275 authenticatee.cpp:259] Received SASL authentication step
I0815 15:13:33.260326 5275 authenticator.cpp:232] Received SASL authentication step
I0815 15:13:33.260494 5275 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f9b3044a9377' server FQDN: 'f9b3044a9377' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0815 15:13:33.260548 5275 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0815 15:13:33.260887 5275 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0815 15:13:33.261060 5275 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f9b3044a9377' server FQDN: 'f9b3044a9377' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0815 15:13:33.261111 5275 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0815 15:13:33.261132 5275 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0815 15:13:33.261334 5275 authenticator.cpp:318] Authentication success
I0815 15:13:33.261649 5279 authenticatee.cpp:299] Authentication success
I0815 15:13:33.261760 5274 master.cpp:7867] Successfully authenticated principal 'test-principal' at slave(87)@172.17.0.2:38361
I0815 15:13:33.261796 5279 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(194)@172.17.0.2:38361
I0815 15:13:33.262316 5276 slave.cpp:1128] Successfully authenticated with master master@172.17.0.2:38361
I0815 15:13:33.262828 5276 slave.cpp:1572] Will retry registration in 6.027974ms if necessary
I0815 15:13:33.263121 5279 master.cpp:5712] Received register agent message from slave(87)@172.17.0.2:38361 (f9b3044a9377)
I0815 15:13:33.263309 5279 master.cpp:3803] Authorizing agent with principal 'test-principal'
*** Aborted at 1502810013 (unix time) try "date -d @1502810013" if you are using GNU date ***
I0815 15:13:33.263967 5274 master.cpp:5772] Authorized registration of agent at slave(87)@172.17.0.2:38361 (f9b3044a9377)
I0815 15:13:33.264161 5274 master.cpp:5865] Registering agent at slave(87)@172.17.0.2:38361 (f9b3044a9377) with id 7d93de72-68b6-442c-a3c6-cfd986790f42-S0
I0815 15:13:33.264932 5274 registrar.cpp:495] Applied 1 operations in 74437ns; attempting to update the registry
I0815 15:13:33.265940 5278 registrar.cpp:552] Successfully updated the registry in 905984ns
I0815 15:13:33.266326 5278 master.cpp:5912] Admitted agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0 at slave(87)@172.17.0.2:38361 (f9b3044a9377)
I0815 15:13:33.267232 5274 slave.cpp:4887] Received ping from slave-observer(82)@172.17.0.2:38361
I0815 15:13:33.267232 5278 master.cpp:5943] Registered agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0 at slave(87)@172.17.0.2:38361 (f9b3044a9377) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0815 15:13:33.267719 5274 slave.cpp:1174] Registered with master master@172.17.0.2:38361; given agent ID 7d93de72-68b6-442c-a3c6-cfd986790f42-S0
I0815 15:13:33.268254 5274 slave.cpp:1194] Checkpointing SlaveInfo to '/tmp/GarbageCollectorIntegrationTest_DiskUsage_aHxWeU/meta/slaves/7d93de72-68b6-442c-a3c6-cfd986790f42-S0/slave.info'
I0815 15:13:33.268779 5272 hierarchical.cpp:593] Added agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0 (f9b3044a9377) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0815 15:13:33.268824 5274 slave.cpp:1232] Forwarding total oversubscribed resources {}
I0815 15:13:33.269568 5272 hierarchical.cpp:1943] No allocations performed
I0815 15:13:33.269651 5272 hierarchical.cpp:1486] Performed allocation for 1 agents in 272667ns
I0815 15:13:33.269682 5279 status_update_manager.cpp:184] Resuming sending status updates
I0815 15:13:33.269825 5274 master.cpp:6688] Received update of agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0 at slave(87)@172.17.0.2:38361 (f9b3044a9377) with total oversubscribed resources {}
I0815 15:13:33.270279 5278 hierarchical.cpp:660] Agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0 (f9b3044a9377) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0815 15:13:33.270316 5253 process.cpp:3228] Attempting to spawn already spawned process version@172.17.0.2:38361
I0815 15:13:33.273058 5253 sched.cpp:232] Version: 1.4.0
I0815 15:13:33.274070 5277 sched.cpp:336] New master detected at master@172.17.0.2:38361
I0815 15:13:33.274499 5277 sched.cpp:407] Authenticating with master master@172.17.0.2:38361
I0815 15:13:33.274529 5277 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0815 15:13:33.275081 5279 authenticatee.cpp:121] Creating new client SASL connection
I0815 15:13:33.275677 5276 master.cpp:7837] Authenticating scheduler-bd16cb3f-54f8-4ef5-9791-d26eef018463@172.17.0.2:38361
I0815 15:13:33.276192 5276 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(195)@172.17.0.2:38361
I0815 15:13:33.276923 5276 authenticator.cpp:98] Creating new server SASL connection
I0815 15:13:33.277484 5274 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0815 15:13:33.277525 5274 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0815 15:13:33.277647 5274 authenticator.cpp:204] Received SASL authentication start
I0815 15:13:33.277704 5274 authenticator.cpp:326] Authentication requires more steps
I0815 15:13:33.277884 5274 authenticatee.cpp:259] Received SASL authentication step
I0815 15:13:33.278103 5274 authenticator.cpp:232] Received SASL authentication step
I0815 15:13:33.278139 5274 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f9b3044a9377' server FQDN: 'f9b3044a9377' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0815 15:13:33.278162 5274 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0815 15:13:33.278215 5274 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0815 15:13:33.278249 5274 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f9b3044a9377' server FQDN: 'f9b3044a9377' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0815 15:13:33.278268 5274 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0815 15:13:33.278285 5274 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0815 15:13:33.278374 5274 authenticator.cpp:318] Authentication success
I0815 15:13:33.278724 5272 authenticatee.cpp:299] Authentication success
I0815 15:13:33.278744 5274 master.cpp:7867] Successfully authenticated principal 'test-principal' at scheduler-bd16cb3f-54f8-4ef5-9791-d26eef018463@172.17.0.2:38361
I0815 15:13:33.278882 5274 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(195)@172.17.0.2:38361
I0815 15:13:33.279412 5278 sched.cpp:513] Successfully authenticated with master master@172.17.0.2:38361
I0815 15:13:33.279449 5278 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.2:38361
I0815 15:13:33.279623 5278 sched.cpp:869] Will retry registration in 1.794272343secs if necessary
I0815 15:13:33.280076 5276 master.cpp:2894] Received SUBSCRIBE call for framework 'default' at scheduler-bd16cb3f-54f8-4ef5-9791-d26eef018463@172.17.0.2:38361
I0815 15:13:33.280387 5276 master.cpp:2228] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0815 15:13:33.281208 5276 master.cpp:2974] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
I0815 15:13:33.282280 5276 sched.cpp:759] Framework registered with 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.282415 5274 hierarchical.cpp:303] Added framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.283082 5276 sched.cpp:773] Scheduler::registered took 749148ns
I0815 15:13:33.284623 5274 hierarchical.cpp:2033] No inverse offers to send out!
I0815 15:13:33.284729 5274 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.985955ms
I0815 15:13:33.285404 5278 master.cpp:7667] Sending 1 offers to framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 (default) at scheduler-bd16cb3f-54f8-4ef5-9791-d26eef018463@172.17.0.2:38361
I0815 15:13:33.287235 5278 sched.cpp:933] Scheduler::resourceOffers took 1.219865ms
I0815 15:13:33.289170 5272 master.cpp:9164] Removing offer 7d93de72-68b6-442c-a3c6-cfd986790f42-O0
I0815 15:13:33.289649 5272 master.cpp:4153] Processing ACCEPT call for offers: [ 7d93de72-68b6-442c-a3c6-cfd986790f42-O0 ] on agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0 at slave(87)@172.17.0.2:38361 (f9b3044a9377) for framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 (default) at scheduler-bd16cb3f-54f8-4ef5-9791-d26eef018463@172.17.0.2:38361
I0815 15:13:33.289975 5272 master.cpp:3530] Authorizing framework principal 'test-principal' to launch task 0
W0815 15:13:33.293345 5274 validation.cpp:1294] Executor 'default' for task '0' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0815 15:13:33.293411 5274 validation.cpp:1306] Executor 'default' for task '0' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0815 15:13:33.294106 5274 master.cpp:9724] Adding task 0 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}] on agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0 at slave(87)@172.17.0.2:38361 (f9b3044a9377)
I0815 15:13:33.294822 5274 master.cpp:4816] Launching task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 (default) at scheduler-bd16cb3f-54f8-4ef5-9791-d26eef018463@172.17.0.2:38361 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}] on agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0 at slave(87)@172.17.0.2:38361 (f9b3044a9377)
I0815 15:13:33.295645 5278 slave.cpp:1701] Got assigned task '0' for framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
PC: @ 0x2b06cab7467a process::EventQueue::Consumer::empty()
I0815 15:13:33.296552 5278 slave.cpp:1982] Authorizing task '0' for framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.296649 5278 slave.cpp:6709] Authorizing framework principal 'test-principal' to launch task 0
I0815 15:13:33.297947 5278 slave.cpp:2169] Launching task '0' for framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
*** SIGSEGV (@0x8) received by PID 5253 (TID 0x2b06d5d82700) from PID 8; stack trace: ***
@ 0x2b06cd215330 (unknown)
I0815 15:13:33.298851 5278 paths.cpp:578] Trying to chown '/tmp/GarbageCollectorIntegrationTest_DiskUsage_aHxWeU/slaves/7d93de72-68b6-442c-a3c6-cfd986790f42-S0/frameworks/7d93de72-68b6-442c-a3c6-cfd986790f42-0000/executors/default/runs/f7e8feb5-0b4e-4507-b349-14b200d48902' to user 'mesos'
I0815 15:13:33.299140 5278 slave.cpp:7165] Launching executor 'default' of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 with resources [] in work directory '/tmp/GarbageCollectorIntegrationTest_DiskUsage_aHxWeU/slaves/7d93de72-68b6-442c-a3c6-cfd986790f42-S0/frameworks/7d93de72-68b6-442c-a3c6-cfd986790f42-0000/executors/default/runs/f7e8feb5-0b4e-4507-b349-14b200d48902'
I0815 15:13:33.299123 5274 hierarchical.cpp:887] Updated allocation of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 on agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0 from cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] to cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000]
I0815 15:13:33.299777 5278 slave.cpp:2856] Launching container f7e8feb5-0b4e-4507-b349-14b200d48902 for executor 'default' of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.300247 5278 slave.cpp:2398] Queued task '0' for executor 'default' of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.300395 5278 slave.cpp:924] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_DiskUsage_aHxWeU/slaves/7d93de72-68b6-442c-a3c6-cfd986790f42-S0/frameworks/7d93de72-68b6-442c-a3c6-cfd986790f42-0000/executors/default/runs/f7e8feb5-0b4e-4507-b349-14b200d48902'
I0815 15:13:33.300933 5277 slave.cpp:924] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_DiskUsage_aHxWeU/slaves/7d93de72-68b6-442c-a3c6-cfd986790f42-S0/frameworks/7d93de72-68b6-442c-a3c6-cfd986790f42-0000/executors/default/runs/f7e8feb5-0b4e-4507-b349-14b200d48902'
I0815 15:13:33.301026 5274 hierarchical.cpp:1161] Recovered ports(allocated: *):[31000-32000]; disk(allocated: *):1024 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: cpus(allocated: *):2; mem(allocated: *):1024) on agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0 from framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.301131 5274 hierarchical.cpp:1207] Framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 filtered agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0 for 5secs
I0815 15:13:33.304672 5276 process.cpp:3228] Attempting to spawn already spawned process version@172.17.0.2:38361
I0815 15:13:33.305151 5276 exec.cpp:162] Version: 1.4.0
I0815 15:13:33.305649 5279 exec.cpp:212] Executor started at: executor(15)@172.17.0.2:38361 with pid 5253
I0815 15:13:33.306159 5277 slave.cpp:3881] Got registration for executor 'default' of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 from executor(15)@172.17.0.2:38361
I0815 15:13:33.307360 5277 exec.cpp:237] Executor registered on agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0
I0815 15:13:33.307443 5277 exec.cpp:249] Executor::registered took 29915ns
I0815 15:13:33.308428 5276 slave.cpp:2603] Sending queued task '0' to executor 'default' of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 at executor(15)@172.17.0.2:38361
I0815 15:13:33.309182 5276 exec.cpp:331] Executor asked to run task '0'
I0815 15:13:33.309497 5276 exec.cpp:340] Executor::launchTask took 267635ns
I0815 15:13:33.309798 5276 exec.cpp:571] Executor sending status update TASK_RUNNING (UUID: 273bbbc9-888a-433c-b629-dd4191117e72) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.310406 5276 slave.cpp:4345] Handling status update TASK_RUNNING (UUID: 273bbbc9-888a-433c-b629-dd4191117e72) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 from executor(15)@172.17.0.2:38361
I0815 15:13:33.311542 5274 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 273bbbc9-888a-433c-b629-dd4191117e72) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.311619 5274 status_update_manager.cpp:500] Creating StatusUpdate stream for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.312356 5274 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 273bbbc9-888a-433c-b629-dd4191117e72) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 to the agent
I0815 15:13:33.312767 5272 slave.cpp:4797] Forwarding the update TASK_RUNNING (UUID: 273bbbc9-888a-433c-b629-dd4191117e72) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 to master@172.17.0.2:38361
I0815 15:13:33.313200 5272 slave.cpp:4691] Status update manager successfully handled status update TASK_RUNNING (UUID: 273bbbc9-888a-433c-b629-dd4191117e72) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.313282 5272 slave.cpp:4707] Sending acknowledgement for status update TASK_RUNNING (UUID: 273bbbc9-888a-433c-b629-dd4191117e72) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 to executor(15)@172.17.0.2:38361
I0815 15:13:33.314100 5272 master.cpp:6846] Status update TASK_RUNNING (UUID: 273bbbc9-888a-433c-b629-dd4191117e72) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 from agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0 at slave(87)@172.17.0.2:38361 (f9b3044a9377)
I0815 15:13:33.314203 5272 master.cpp:6908] Forwarding status update TASK_RUNNING (UUID: 273bbbc9-888a-433c-b629-dd4191117e72) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.314781 5272 master.cpp:8933] Updating the state of task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0815 15:13:33.315140 5272 exec.cpp:388] Executor received status update acknowledgement 273bbbc9-888a-433c-b629-dd4191117e72 for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.315820 5272 sched.cpp:1041] Scheduler::statusUpdate took 146610ns
I0815 15:13:33.316646 5277 master.cpp:5477] Processing ACKNOWLEDGE call 273bbbc9-888a-433c-b629-dd4191117e72 for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 (default) at scheduler-bd16cb3f-54f8-4ef5-9791-d26eef018463@172.17.0.2:38361 on agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0
I0815 15:13:33.317476 5274 slave.cpp:4929] Got exited event for executor(15)@172.17.0.2:38361
I0815 15:13:33.317849 5272 slave.cpp:5329] Executor 'default' of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 exited with status 0
I0815 15:13:33.318195 5272 slave.cpp:4345] Handling status update TASK_FAILED (UUID: 9eaea6d9-ddcf-4531-9ceb-61e8ccff5a3c) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 from @0.0.0.0:0
I0815 15:13:33.318457 5279 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 273bbbc9-888a-433c-b629-dd4191117e72) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.319386 5272 slave.cpp:3609] Status update manager successfully handled status update acknowledgement (UUID: 273bbbc9-888a-433c-b629-dd4191117e72) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.319638 5273 master.cpp:6969] Executor 'default' of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 on agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0 at slave(87)@172.17.0.2:38361 (f9b3044a9377): exited with status 0
I0815 15:13:33.319772 5273 master.cpp:9056] Removing executor 'default' with resources [] of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 on agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0 at slave(87)@172.17.0.2:38361 (f9b3044a9377)
I0815 15:13:33.320505 5273 sched.cpp:1143] Executor default on agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0 exited with status 0
I0815 15:13:33.320574 5273 sched.cpp:1154] Scheduler::executorLost took 31158ns
I0815 15:13:33.321072 5274 status_update_manager.cpp:323] Received status update TASK_FAILED (UUID: 9eaea6d9-ddcf-4531-9ceb-61e8ccff5a3c) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.321449 5274 status_update_manager.cpp:377] Forwarding update TASK_FAILED (UUID: 9eaea6d9-ddcf-4531-9ceb-61e8ccff5a3c) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 to the agent
I0815 15:13:33.321905 5277 slave.cpp:4797] Forwarding the update TASK_FAILED (UUID: 9eaea6d9-ddcf-4531-9ceb-61e8ccff5a3c) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 to master@172.17.0.2:38361
I0815 15:13:33.322330 5277 slave.cpp:4691] Status update manager successfully handled status update TASK_FAILED (UUID: 9eaea6d9-ddcf-4531-9ceb-61e8ccff5a3c) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.322854 5273 master.cpp:6846] Status update TASK_FAILED (UUID: 9eaea6d9-ddcf-4531-9ceb-61e8ccff5a3c) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 from agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0 at slave(87)@172.17.0.2:38361 (f9b3044a9377)
I0815 15:13:33.322942 5273 master.cpp:6908] Forwarding status update TASK_FAILED (UUID: 9eaea6d9-ddcf-4531-9ceb-61e8ccff5a3c) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.323331 5273 master.cpp:8933] Updating the state of task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 (latest state: TASK_FAILED, status update state: TASK_FAILED)
I0815 15:13:33.323774 5279 sched.cpp:1041] Scheduler::statusUpdate took 28562ns
I0815 15:13:33.324483 5273 master.cpp:5477] Processing ACKNOWLEDGE call 9eaea6d9-ddcf-4531-9ceb-61e8ccff5a3c for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 (default) at scheduler-bd16cb3f-54f8-4ef5-9791-d26eef018463@172.17.0.2:38361 on agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0
I0815 15:13:33.324596 5273 master.cpp:9027] Removing task 0 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}] of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 on agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0 at slave(87)@172.17.0.2:38361 (f9b3044a9377)
I0815 15:13:33.325345 5279 hierarchical.cpp:1161] Recovered cpus(allocated: *):2; mem(allocated: *):1024 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent 7d93de72-68b6-442c-a3c6-cfd986790f42-S0 from framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.325886 5274 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 9eaea6d9-ddcf-4531-9ceb-61e8ccff5a3c) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.326216 5274 status_update_manager.cpp:531] Cleaning up status update stream for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.326865 5277 slave.cpp:3609] Status update manager successfully handled status update acknowledgement (UUID: 9eaea6d9-ddcf-4531-9ceb-61e8ccff5a3c) for task 0 of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.326956 5277 slave.cpp:7561] Completing task 0
I0815 15:13:33.327055 5277 slave.cpp:5429] Cleaning up executor 'default' of framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000 at executor(15)@172.17.0.2:38361
I0815 15:13:33.327507 5276 gc.cpp:59] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_aHxWeU/slaves/7d93de72-68b6-442c-a3c6-cfd986790f42-S0/frameworks/7d93de72-68b6-442c-a3c6-cfd986790f42-0000/executors/default/runs/f7e8feb5-0b4e-4507-b349-14b200d48902' for gc 6.99999633686518days in the future
I0815 15:13:33.327704 5277 slave.cpp:5525] Cleaning up framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.328034 5276 gc.cpp:59] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_aHxWeU/slaves/7d93de72-68b6-442c-a3c6-cfd986790f42-S0/frameworks/7d93de72-68b6-442c-a3c6-cfd986790f42-0000/executors/default' for gc 6.99999633686518days in the future
I0815 15:13:33.328256 5277 status_update_manager.cpp:285] Closing status update streams for framework 7d93de72-68b6-442c-a3c6-cfd986790f42-0000
I0815 15:13:33.328310 5276 gc.cpp:59] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_aHxWeU/slaves/7d93de72-68b6-442c-a3c6-cfd986790f42-S0/frameworks/7d93de72-68b6-442c-a3c6-cfd986790f42-0000' for gc 6.99999633686518days in the future
@ 0x2b06cab7467a process::EventQueue::Consumer::empty()
@ 0x2b06cab61328 process::ProcessManager::resume()
@ 0x2b06cab5d924 _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
@ 0x2b06cab6f092 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2b06cab6efe9 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
@ 0x2b06cab6ef82 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
@ 0x2b06cca96a60 (unknown)
@ 0x2b06cd20d184 start_thread
@ 0x2b06cd520ffd (unknown)
make[4]: *** [check-local] Segmentation fault (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.4.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1502802925-11689
Untagged: mesos-1502802925-11689:latest
Deleted: sha256:eb2ae61319122a59358012da1bd6ad4be747c37aaef3519a4e4acc5e863a9a25
Deleted: sha256:d595da56be15f943d113b9c69da43ff81b088969eed06717242719a163a58b90
Deleted: sha256:ef12a32cb8dce95233b01388eb12669ec51159452dae21dc2d25a383fe023c05
Deleted: sha256:d19276630c2615e1e628354188a2efe2ee17a89004f0afb63cc3b64b3f80c318
Deleted: sha256:e9639838f0059482d08d964e2f8dc535d7f7cdb9b74d2009ba49065b03da904d
Deleted: sha256:cb24496bbd79ccba0f576a5a8a39881cbddc07fb92553598e11179d493e2089e
Deleted: sha256:06c11aa53a33e939e54e7957ccb76fdb115e500a43cde84390a59a63fb4702b1
Deleted: sha256:b4b90a98feff70a76ebbcb0078825198500f83052d5a06fdbb975b566c1e4717
Deleted: sha256:ba3539613054b7730cc608f2bc1eafdb615ad1af06af0ca2660f60649ed49251
Deleted: sha256:0328072a72226dcb992fa6e1cd3d0441d9f8bb491e1e8417d42bde6676368082
Deleted: sha256:04ba7d483a81caa718a4bf32b23637d368c69baaac8fe77f4e1a016d6dfe28c9
Deleted: sha256:8f949f49a117e0a7c6742d66c23ee9d6b1ba4e6dbfd62febb8e15ab47c82c810
Deleted: sha256:f7cf43f4ea4ca76946ddd8fd30622eca1cb6138b05f9ecd47c0133a02cce3992
Deleted: sha256:cc3df9630b2b6228f82ffad5e32151aed2286079545041a1a380962efa3dd0b9
Deleted: sha256:f6fdefea4639f9552e5867899e1ade1a3afab9011fbca699dc9e4e9d36731e8b
Deleted: sha256:4fea21742fb916f185c6d99ebce76bf641ca82dceca280126e98e50f86007854
Deleted: sha256:8c43f9e433c3cc860dfc91df00e0720acc9040123fcaed55578fe4dc3ee796c9
Deleted: sha256:b0ac0b24d59748b68a89d073e898794464dd0006fd0bbcf7162a3a89110266eb
Deleted: sha256:3ec0298d917e8a4e433a53b4797d1b7d1f973b6e880760e939deb93bb99a8179
Deleted: sha256:dbe8f88934167cb421b3a1bad365ee145622e0ec611d77dd9891a08bd81fdeab
Deleted: sha256:52f96354f98b6220e95d07ff9087eb8fe6e3dc37bda0893f79056948767d2b2e
Deleted: sha256:61d29ef8d16d38994801217998608201a6cd537aa5182dbb854342c71081797d
Deleted: sha256:c01b21f5ec8ddb8274f8e63a6b90429e54c40a4ce583eeb4663a29176188220a
Deleted: sha256:3449af1b58ca85a33ed63f799380a51354ea8f296a11e5afc9ba3b28e345852b
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Reviewbot #18881
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/18881/display/redirect>
Build failed in Jenkins: Mesos-Reviewbot #18880
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/18880/display/redirect>
------------------------------------------
[URLTrigger] A change within the response URL invocation (log)
[EnvInject] - Loading node environment variables.
Building remotely on H21 (couchdbtest ubuntu xenial) in workspace <https://builds.apache.org/job/Mesos-Reviewbot/ws/>
Cloning the remote Git repository
Cloning repository https://git-wip-us.apache.org/repos/asf/mesos.git
> git init <https://builds.apache.org/job/Mesos-Reviewbot/ws/> # timeout=10
Fetching upstream changes from https://git-wip-us.apache.org/repos/asf/mesos.git
> git --version # timeout=10
> git fetch --tags --progress https://git-wip-us.apache.org/repos/asf/mesos.git +refs/heads/*:refs/remotes/origin/*
> git config remote.origin.url https://git-wip-us.apache.org/repos/asf/mesos.git # timeout=10
> git config --add remote.origin.fetch +refs/heads/*:refs/remotes/origin/* # timeout=10
> git config remote.origin.url https://git-wip-us.apache.org/repos/asf/mesos.git # timeout=10
Fetching upstream changes from https://git-wip-us.apache.org/repos/asf/mesos.git
> git fetch --tags --progress https://git-wip-us.apache.org/repos/asf/mesos.git +refs/heads/*:refs/remotes/origin/*
> git rev-parse origin/master^{commit} # timeout=10
Checking out Revision e335106aa0406210a5faa10d5657701ca6fbb80c (origin/master)
Commit message: "Moved MESOS-7660 to the appropriate CHANGELOG section."
> git config core.sparsecheckout # timeout=10
> git checkout -f e335106aa0406210a5faa10d5657701ca6fbb80c
> git rev-list e335106aa0406210a5faa10d5657701ca6fbb80c # timeout=10
> git tag -a -f -m Jenkins Build #18880 jenkins-Mesos-Reviewbot-18880 # timeout=10
[EnvInject] - Executing scripts and injecting environment variables after the SCM step.
[EnvInject] - Injecting as environment variables the properties content
USERNAME=mesos-review
[EnvInject] - Variables injected successfully.
[EnvInject] - Mask passwords that will be passed as build parameters.
FATAL: Unable to produce a script file
java.io.IOException: Permission denied
at java.io.UnixFileSystem.createFileExclusively(Native Method)
at java.io.File.createTempFile(File.java:2024)
at hudson.FilePath$17.invoke(FilePath.java:1373)
at hudson.FilePath$17.invoke(FilePath.java:1363)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2739)
at hudson.remoting.UserRequest.perform(UserRequest.java:153)
at hudson.remoting.UserRequest.perform(UserRequest.java:50)
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)
Caused: java.io.IOException: Failed to create a temporary directory in /tmp
at hudson.FilePath$17.invoke(FilePath.java:1375)
at hudson.FilePath$17.invoke(FilePath.java:1363)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2739)
at hudson.remoting.UserRequest.perform(UserRequest.java:153)
at hudson.remoting.UserRequest.perform(UserRequest.java:50)
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 H21(Native Method)
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1545)
at hudson.remoting.UserResponse.retrieve(UserRequest.java:253)
at hudson.remoting.Channel.call(Channel.java:830)
at hudson.FilePath.act(FilePath.java:986)
Caused: java.io.IOException: remote file operation failed: <https://builds.apache.org/job/Mesos-Reviewbot/ws/> at hudson.remoting.Channel@4b715ff3:H21
at hudson.FilePath.act(FilePath.java:993)
at hudson.FilePath.act(FilePath.java:975)
at hudson.FilePath.createTextTempFile(FilePath.java:1363)
Caused: java.io.IOException: Failed to create a temp file on <https://builds.apache.org/job/Mesos-Reviewbot/ws/>
at hudson.FilePath.createTextTempFile(FilePath.java:1386)
at hudson.tasks.CommandInterpreter.createScriptFile(CommandInterpreter.java:162)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:94)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:66)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:735)
at hudson.model.Build$BuildExecution.build(Build.java:206)
at hudson.model.Build$BuildExecution.doRun(Build.java:163)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:490)
at hudson.model.Run.execute(Run.java:1735)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:405)
Build step 'Execute shell' marked build as failure
Build failed in Jenkins: Mesos-Reviewbot #18879
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/18879/display/redirect?page=changes>
Changes:
[alexr] Added entry for 1.5.0 to CHANGELOG.
[alexr] Moved MESOS-7660 to the appropriate CHANGELOG section.
------------------------------------------
[...truncated 23.82 MB...]
I0815 17:30:13.644903 5263 replica.cpp:711] Persisted action APPEND at position 1
I0815 17:30:13.644975 5266 replica.cpp:711] Persisted action APPEND at position 1
I0815 17:30:13.646183 5265 replica.cpp:694] Replica received learned notice for position 1 from log-network(89)@172.17.0.2:46055
I0815 17:30:13.646510 5264 replica.cpp:694] Replica received learned notice for position 1 from log-network(89)@172.17.0.2:46055
I0815 17:30:13.694777 5265 leveldb.cpp:341] Persisting action (68 bytes) to leveldb took 48.521462ms
I0815 17:30:13.694849 5265 replica.cpp:711] Persisted action APPEND at position 1
I0815 17:30:13.694793 5264 leveldb.cpp:341] Persisting action (68 bytes) to leveldb took 48.176924ms
I0815 17:30:13.694975 5264 replica.cpp:711] Persisted action APPEND at position 1
I0815 17:30:13.696609 5270 log.cpp:579] Attempting to truncate the log to 1
I0815 17:30:13.697016 5268 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0815 17:30:13.698361 5263 replica.cpp:540] Replica received write request for position 2 from __req_res__(1628)@172.17.0.2:46055
I0815 17:30:13.698395 5266 replica.cpp:540] Replica received write request for position 2 from __req_res__(1629)@172.17.0.2:46055
I0815 17:30:13.744961 5266 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 46.412795ms
I0815 17:30:13.744957 5263 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 46.29597ms
I0815 17:30:13.745091 5266 replica.cpp:711] Persisted action TRUNCATE at position 2
I0815 17:30:13.745293 5263 replica.cpp:711] Persisted action TRUNCATE at position 2
I0815 17:30:13.746381 5268 replica.cpp:694] Replica received learned notice for position 2 from log-network(89)@172.17.0.2:46055
I0815 17:30:13.746562 5264 replica.cpp:694] Replica received learned notice for position 2 from log-network(89)@172.17.0.2:46055
I0815 17:30:13.794800 5264 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 48.125744ms
I0815 17:30:13.794801 5268 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 48.343393ms
I0815 17:30:13.795013 5264 leveldb.cpp:399] Deleting ~1 keys from leveldb took 99862ns
I0815 17:30:13.795204 5268 leveldb.cpp:399] Deleting ~1 keys from leveldb took 142981ns
I0815 17:30:13.795308 5268 replica.cpp:711] Persisted action TRUNCATE at position 2
I0815 17:30:13.795419 5264 replica.cpp:711] Persisted action TRUNCATE at position 2
[ OK ] LogStateTest.FetchAndStoreAndStoreFailAndFetch (429 ms)
[ RUN ] LogStateTest.FetchAndStoreAndExpungeAndFetch
I0815 17:30:13.811851 5244 leveldb.cpp:174] Opened db in 9.610978ms
I0815 17:30:13.814700 5244 leveldb.cpp:181] Compacted db in 2.765793ms
I0815 17:30:13.814815 5244 leveldb.cpp:196] Created db iterator in 31249ns
I0815 17:30:13.814841 5244 leveldb.cpp:202] Seeked to beginning of db in 3189ns
I0815 17:30:13.814862 5244 leveldb.cpp:271] Iterated through 0 keys in the db in 632ns
I0815 17:30:13.814923 5244 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0815 17:30:13.817647 5264 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 2.161951ms
I0815 17:30:13.817744 5264 replica.cpp:322] Persisted replica status to VOTING
I0815 17:30:13.828716 5244 leveldb.cpp:174] Opened db in 10.012431ms
I0815 17:30:13.831588 5244 leveldb.cpp:181] Compacted db in 2.786425ms
I0815 17:30:13.831701 5244 leveldb.cpp:196] Created db iterator in 31309ns
I0815 17:30:13.831727 5244 leveldb.cpp:202] Seeked to beginning of db in 3097ns
I0815 17:30:13.831749 5244 leveldb.cpp:271] Iterated through 0 keys in the db in 531ns
I0815 17:30:13.831812 5244 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0815 17:30:13.834623 5266 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 2.262354ms
I0815 17:30:13.834720 5266 replica.cpp:322] Persisted replica status to VOTING
I0815 17:30:13.845448 5244 leveldb.cpp:174] Opened db in 9.732025ms
I0815 17:30:13.852723 5244 leveldb.cpp:181] Compacted db in 7.190357ms
I0815 17:30:13.852846 5244 leveldb.cpp:196] Created db iterator in 41555ns
I0815 17:30:13.852910 5244 leveldb.cpp:202] Seeked to beginning of db in 40282ns
I0815 17:30:13.852980 5244 leveldb.cpp:271] Iterated through 1 keys in the db in 46678ns
I0815 17:30:13.853041 5244 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0815 17:30:13.862804 5244 leveldb.cpp:174] Opened db in 9.397039ms
I0815 17:30:13.870784 5244 leveldb.cpp:181] Compacted db in 7.894642ms
I0815 17:30:13.870908 5244 leveldb.cpp:196] Created db iterator in 42036ns
I0815 17:30:13.870971 5244 leveldb.cpp:202] Seeked to beginning of db in 39680ns
I0815 17:30:13.871040 5244 leveldb.cpp:271] Iterated through 1 keys in the db in 46427ns
I0815 17:30:13.871103 5244 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0815 17:30:13.872222 5263 recover.cpp:451] Starting replica recovery
I0815 17:30:13.872833 5263 recover.cpp:477] Replica is in VOTING status
I0815 17:30:13.873242 5263 recover.cpp:466] Recover process terminated
I0815 17:30:13.873915 5266 log.cpp:536] Attempting to start the writer
I0815 17:30:13.875635 5270 replica.cpp:496] Replica received implicit promise request from __req_res__(1630)@172.17.0.2:46055 with proposal 1
I0815 17:30:13.875679 5269 replica.cpp:496] Replica received implicit promise request from __req_res__(1631)@172.17.0.2:46055 with proposal 1
I0815 17:30:13.878294 5269 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 2.546229ms
I0815 17:30:13.878355 5269 replica.cpp:344] Persisted promised to 1
I0815 17:30:13.878315 5270 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 2.601759ms
I0815 17:30:13.878731 5270 replica.cpp:344] Persisted promised to 1
I0815 17:30:13.879565 5264 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0815 17:30:13.881470 5264 replica.cpp:391] Replica received explicit promise request from __req_res__(1632)@172.17.0.2:46055 for position 0 with proposal 2
I0815 17:30:13.881525 5270 replica.cpp:391] Replica received explicit promise request from __req_res__(1633)@172.17.0.2:46055 for position 0 with proposal 2
I0815 17:30:13.924960 5264 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 43.365554ms
I0815 17:30:13.925076 5264 replica.cpp:711] Persisted action NOP at position 0
I0815 17:30:13.924957 5270 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 42.955761ms
I0815 17:30:13.925420 5270 replica.cpp:711] Persisted action NOP at position 0
I0815 17:30:13.927240 5266 replica.cpp:540] Replica received write request for position 0 from __req_res__(1634)@172.17.0.2:46055
I0815 17:30:13.927243 5267 replica.cpp:540] Replica received write request for position 0 from __req_res__(1635)@172.17.0.2:46055
I0815 17:30:13.927534 5266 leveldb.cpp:436] Reading position from leveldb took 188555ns
I0815 17:30:13.927762 5267 leveldb.cpp:436] Reading position from leveldb took 178620ns
I0815 17:30:13.976405 5266 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 48.740764ms
I0815 17:30:13.976599 5266 replica.cpp:711] Persisted action NOP at position 0
I0815 17:30:13.976425 5267 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 48.585082ms
I0815 17:30:13.976882 5267 replica.cpp:711] Persisted action NOP at position 0
I0815 17:30:13.978060 5264 replica.cpp:694] Replica received learned notice for position 0 from log-network(90)@172.17.0.2:46055
I0815 17:30:13.978085 5263 replica.cpp:694] Replica received learned notice for position 0 from log-network(90)@172.17.0.2:46055
I0815 17:30:14.025033 5263 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 46.69234ms
I0815 17:30:14.025044 5264 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 46.847642ms
I0815 17:30:14.025202 5264 replica.cpp:711] Persisted action NOP at position 0
I0815 17:30:14.025115 5263 replica.cpp:711] Persisted action NOP at position 0
I0815 17:30:14.026271 5269 log.cpp:552] Writer started with ending position 0
I0815 17:30:14.029619 5269 leveldb.cpp:436] Reading position from leveldb took 95290ns
I0815 17:30:14.031873 5269 log.cpp:560] Attempting to append 49 bytes to the log
I0815 17:30:14.032122 5265 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0815 17:30:14.033413 5268 replica.cpp:540] Replica received write request for position 1 from __req_res__(1637)@172.17.0.2:46055
I0815 17:30:14.033427 5266 replica.cpp:540] Replica received write request for position 1 from __req_res__(1636)@172.17.0.2:46055
I0815 17:30:14.084756 5266 leveldb.cpp:341] Persisting action (65 bytes) to leveldb took 51.20208ms
I0815 17:30:14.084782 5268 leveldb.cpp:341] Persisting action (65 bytes) to leveldb took 51.283415ms
I0815 17:30:14.084885 5266 replica.cpp:711] Persisted action APPEND at position 1
I0815 17:30:14.084923 5268 replica.cpp:711] Persisted action APPEND at position 1
I0815 17:30:14.085842 5265 replica.cpp:694] Replica received learned notice for position 1 from log-network(90)@172.17.0.2:46055
I0815 17:30:14.086156 5263 replica.cpp:694] Replica received learned notice for position 1 from log-network(90)@172.17.0.2:46055
I0815 17:30:14.134857 5265 leveldb.cpp:341] Persisting action (67 bytes) to leveldb took 48.948519ms
I0815 17:30:14.134876 5263 leveldb.cpp:341] Persisting action (67 bytes) to leveldb took 48.627499ms
I0815 17:30:14.134937 5265 replica.cpp:711] Persisted action APPEND at position 1
I0815 17:30:14.134956 5263 replica.cpp:711] Persisted action APPEND at position 1
I0815 17:30:14.137470 5263 log.cpp:579] Attempting to truncate the log to 1
I0815 17:30:14.137843 5265 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0815 17:30:14.139243 5266 replica.cpp:540] Replica received write request for position 2 from __req_res__(1638)@172.17.0.2:46055
I0815 17:30:14.139734 5270 replica.cpp:540] Replica received write request for position 2 from __req_res__(1639)@172.17.0.2:46055
I0815 17:30:14.185042 5270 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 45.219907ms
I0815 17:30:14.185070 5266 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 45.696028ms
I0815 17:30:14.185325 5266 replica.cpp:711] Persisted action TRUNCATE at position 2
I0815 17:30:14.185117 5270 replica.cpp:711] Persisted action TRUNCATE at position 2
I0815 17:30:14.186529 5263 replica.cpp:694] Replica received learned notice for position 2 from log-network(90)@172.17.0.2:46055
I0815 17:30:14.186607 5267 replica.cpp:694] Replica received learned notice for position 2 from log-network(90)@172.17.0.2:46055
I0815 17:30:14.227109 5267 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 40.411638ms
I0815 17:30:14.227119 5263 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 40.515118ms
I0815 17:30:14.227257 5263 leveldb.cpp:399] Deleting ~1 keys from leveldb took 68582ns
I0815 17:30:14.227244 5267 leveldb.cpp:399] Deleting ~1 keys from leveldb took 66818ns
I0815 17:30:14.227300 5267 replica.cpp:711] Persisted action TRUNCATE at position 2
I0815 17:30:14.227289 5263 replica.cpp:711] Persisted action TRUNCATE at position 2
I0815 17:30:14.228852 5270 log.cpp:560] Attempting to append 12 bytes to the log
I0815 17:30:14.229110 5269 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 3
I0815 17:30:14.230450 5270 replica.cpp:540] Replica received write request for position 3 from __req_res__(1640)@172.17.0.2:46055
I0815 17:30:14.230661 5264 replica.cpp:540] Replica received write request for position 3 from __req_res__(1641)@172.17.0.2:46055
I0815 17:30:14.275025 5270 leveldb.cpp:341] Persisting action (28 bytes) to leveldb took 44.478139ms
I0815 17:30:14.275105 5270 replica.cpp:711] Persisted action APPEND at position 3
I0815 17:30:14.275048 5264 leveldb.cpp:341] Persisting action (28 bytes) to leveldb took 44.215316ms
I0815 17:30:14.275329 5264 replica.cpp:711] Persisted action APPEND at position 3
I0815 17:30:14.276273 5263 replica.cpp:694] Replica received learned notice for position 3 from log-network(90)@172.17.0.2:46055
I0815 17:30:14.276451 5265 replica.cpp:694] Replica received learned notice for position 3 from log-network(90)@172.17.0.2:46055
I0815 17:30:14.324997 5265 leveldb.cpp:341] Persisting action (30 bytes) to leveldb took 48.429639ms
I0815 17:30:14.325017 5263 leveldb.cpp:341] Persisting action (30 bytes) to leveldb took 48.672461ms
I0815 17:30:14.325073 5265 replica.cpp:711] Persisted action APPEND at position 3
I0815 17:30:14.325093 5263 replica.cpp:711] Persisted action APPEND at position 3
[ OK ] LogStateTest.FetchAndStoreAndExpungeAndFetch (529 ms)
[ RUN ] LogStateTest.FetchAndStoreAndExpungeAndExpunge
I0815 17:30:14.340595 5244 leveldb.cpp:174] Opened db in 9.27787ms
I0815 17:30:14.343452 5244 leveldb.cpp:181] Compacted db in 2.774946ms
I0815 17:30:14.343564 5244 leveldb.cpp:196] Created db iterator in 30747ns
I0815 17:30:14.343593 5244 leveldb.cpp:202] Seeked to beginning of db in 3348ns
I0815 17:30:14.343616 5244 leveldb.cpp:271] Iterated through 0 keys in the db in 542ns
I0815 17:30:14.343677 5244 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0815 17:30:14.346745 5263 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 2.422928ms
I0815 17:30:14.346812 5263 replica.cpp:322] Persisted replica status to VOTING
I0815 17:30:14.358101 5244 leveldb.cpp:174] Opened db in 10.053905ms
I0815 17:30:14.360924 5244 leveldb.cpp:181] Compacted db in 2.739416ms
I0815 17:30:14.361038 5244 leveldb.cpp:196] Created db iterator in 32672ns
I0815 17:30:14.361065 5244 leveldb.cpp:202] Seeked to beginning of db in 3239ns
I0815 17:30:14.361088 5244 leveldb.cpp:271] Iterated through 0 keys in the db in 562ns
I0815 17:30:14.361147 5244 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0815 17:30:14.364094 5264 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 2.404351ms
I0815 17:30:14.364248 5264 replica.cpp:322] Persisted replica status to VOTING
I0815 17:30:14.374732 5244 leveldb.cpp:174] Opened db in 9.493182ms
I0815 17:30:14.381919 5244 leveldb.cpp:181] Compacted db in 7.105954ms
I0815 17:30:14.382040 5244 leveldb.cpp:196] Created db iterator in 41354ns
I0815 17:30:14.382102 5244 leveldb.cpp:202] Seeked to beginning of db in 38557ns
I0815 17:30:14.382170 5244 leveldb.cpp:271] Iterated through 1 keys in the db in 45244ns
I0815 17:30:14.382231 5244 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0815 17:30:14.392118 5244 leveldb.cpp:174] Opened db in 9.530396ms
I0815 17:30:14.398756 5244 leveldb.cpp:181] Compacted db in 6.555416ms
I0815 17:30:14.398882 5244 leveldb.cpp:196] Created db iterator in 43289ns
I0815 17:30:14.398947 5244 leveldb.cpp:202] Seeked to beginning of db in 38366ns
I0815 17:30:14.399018 5244 leveldb.cpp:271] Iterated through 1 keys in the db in 47029ns
I0815 17:30:14.399081 5244 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0815 17:30:14.400094 5269 recover.cpp:451] Starting replica recovery
I0815 17:30:14.400506 5269 recover.cpp:477] Replica is in VOTING status
I0815 17:30:14.400889 5269 recover.cpp:466] Recover process terminated
I0815 17:30:14.401396 5263 log.cpp:536] Attempting to start the writer
I0815 17:30:14.403096 5264 replica.cpp:496] Replica received implicit promise request from __req_res__(1642)@172.17.0.2:46055 with proposal 1
I0815 17:30:14.403158 5263 replica.cpp:496] Replica received implicit promise request from __req_res__(1643)@172.17.0.2:46055 with proposal 1
I0815 17:30:14.405604 5263 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 2.115303ms
I0815 17:30:14.405671 5263 replica.cpp:344] Persisted promised to 1
I0815 17:30:14.405623 5264 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 2.36439ms
I0815 17:30:14.405803 5264 replica.cpp:344] Persisted promised to 1
I0815 17:30:14.406754 5264 coordinator.cpp:238] Coordinator attempting to fill missing positions
*** Aborted at 1502818214 (unix time) try "date -d @1502818214" if you are using GNU date ***
I0815 17:30:14.408690 5266 replica.cpp:391] Replica received explicit promise request from __req_res__(1644)@172.17.0.2:46055 for position 0 with proposal 2
I0815 17:30:14.409052 5270 replica.cpp:391] Replica received explicit promise request from __req_res__(1645)@172.17.0.2:46055 for position 0 with proposal 2
I0815 17:30:14.410910 5270 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 1.706372ms
I0815 17:30:14.410917 5266 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 2.023712ms
I0815 17:30:14.411100 5266 replica.cpp:711] Persisted action NOP at position 0
I0815 17:30:14.410974 5270 replica.cpp:711] Persisted action NOP at position 0
I0815 17:30:14.413017 5268 replica.cpp:540] Replica received write request for position 0 from __req_res__(1646)@172.17.0.2:46055
I0815 17:30:14.413018 5266 replica.cpp:540] Replica received write request for position 0 from __req_res__(1647)@172.17.0.2:46055
I0815 17:30:14.413143 5268 leveldb.cpp:436] Reading position from leveldb took 51078ns
I0815 17:30:14.413378 5266 leveldb.cpp:436] Reading position from leveldb took 181918ns
PC: @ 0x2acdf7d3867a process::EventQueue::Consumer::empty()
I0815 17:30:14.426941 5266 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 13.398679ms
I0815 17:30:14.426950 5268 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 13.622152ms
I0815 17:30:14.427063 5266 replica.cpp:711] Persisted action NOP at position 0
I0815 17:30:14.427224 5268 replica.cpp:711] Persisted action NOP at position 0
I0815 17:30:14.428189 5266 replica.cpp:694] Replica received learned notice for position 0 from log-network(91)@172.17.0.2:46055
I0815 17:30:14.428457 5270 replica.cpp:694] Replica received learned notice for position 0 from log-network(91)@172.17.0.2:46055
*** SIGSEGV (@0x8) received by PID 5244 (TID 0x2ace02d45700) from PID 8; stack trace: ***
I0815 17:30:14.435797 5266 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 7.495887ms
I0815 17:30:14.435897 5266 replica.cpp:711] Persisted action NOP at position 0
@ 0x2ace2a197417 (unknown)
I0815 17:30:14.435845 5270 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 7.30663ms
I0815 17:30:14.436484 5270 replica.cpp:711] Persisted action NOP at position 0
I0815 17:30:14.437495 5268 log.cpp:552] Writer started with ending position 0
@ 0x2ace2a19b6f9 (unknown)
@ 0x2acdfa3d9330 (unknown)
I0815 17:30:14.439194 5263 leveldb.cpp:436] Reading position from leveldb took 86097ns
I0815 17:30:14.441635 5268 log.cpp:560] Attempting to append 49 bytes to the log
I0815 17:30:14.441921 5269 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0815 17:30:14.442967 5263 replica.cpp:540] Replica received write request for position 1 from __req_res__(1649)@172.17.0.2:46055
I0815 17:30:14.442984 5266 replica.cpp:540] Replica received write request for position 1 from __req_res__(1648)@172.17.0.2:46055
@ 0x2acdf7d3867a process::EventQueue::Consumer::empty()
@ 0x2acdf7d25328 process::ProcessManager::resume()
@ 0x2acdf7d21924 _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
@ 0x2acdf7d33092 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2acdf7d32fe9 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
@ 0x2acdf7d32f82 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
@ 0x2acdf9c5aa60 (unknown)
@ 0x2acdfa3d1184 start_thread
@ 0x2acdfa6e4ffd (unknown)
make[4]: *** [check-local] Segmentation fault (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.4.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1502810386-24508
Untagged: mesos-1502810386-24508:latest
Deleted: sha256:004c06b331c84bdc8e12dda144bfe49968f96ce72f28a89a83a0410c6e703f31
Deleted: sha256:5a964f902d16086c2ccb28dc511b6e71750e7e6421bb977a740cedebc9bc00a7
Deleted: sha256:161ba56a114b6f8787270f0cc5a4c6098c283fb0ff3dc361c01b6df9f5f78f12
Deleted: sha256:745ccaa8925f66b8432ada5d2b9064de78e5ecaf8c69a186b434819b661cfa79
Deleted: sha256:afba30f5e781d4ad29aa8c606c3f4d1a80df521e4ae247fa987595c940611a0d
Deleted: sha256:f3f4573b93819d38c52a2abfb026a82c331c91980c4b6d7c44df17287723c7f5
Deleted: sha256:b1189b8e784fe3e1ccb9858c81790d0148828182664b67f27f2f1e9ec188ad47
Deleted: sha256:617d5ff6b476189ca2a2943c0a23ee668267d055d2cdd849d0d93ad9d9ec2003
Deleted: sha256:0f9e25f1174d4cb1ef7c2227f6fa9d846d36082f115d823cda703f9cc3f3fc75
Deleted: sha256:3d30b765dc8d752ce21f09811a032d5d0cf5c1d721bf9c9193ed49bbd083ed33
Deleted: sha256:5353160c74a41f1277b6e8072e90b8d108543affaa7ca42ab686dc60b9a9d71a
Deleted: sha256:de1bb0d99b734ca2dc1e49686887e28b883503ef8ddf732c88638743262d63ba
Deleted: sha256:664dc2f51c0f7d04c34bd3e9eace8cea68e44cf4fe702c904183e28f8327f4c4
Deleted: sha256:2933214cd6d873847815d48123efbd7e9b5a5b1db40ed0534b25bad1acdd42b9
Deleted: sha256:048861ff44dfdcee5a9c6672f499a7ba95d19d389051887d2ac1f0ee45556ad3
Deleted: sha256:f1bfaa8d4d4e60d7ef3bdf21cd630ab88107ac0221af333a09162288c3dfef03
Deleted: sha256:e5c0319878c4051674556e346a28cbbd2705a664658d0f15f102ae0349774146
Deleted: sha256:3cf652c069eba15b9209c61d95c49ebe837ed3c41b2c6be058363faab2f14719
Deleted: sha256:1c57c62c819acefe314b8a409b6a5d6fa7cd9839df58136e23e83a0643ecf3f4
Deleted: sha256:052387f48b7cfa371d18e4cd6f8fd7e94585ebdb7f7f2546c34e99bc7a654bdd
Deleted: sha256:d28586678b7606e95f2ba803d90eec290febee77a365167802e811aeef062dfa
Deleted: sha256:c8119923223b13022586d6ca59c7a83b12858c5e76f95ae50801c84e2b5a1d9a
Deleted: sha256:ebd4ef53d12e35bc38d803a332ee673d700323654c99c42e886bfe52dabdf878
Deleted: sha256:96335333d58443aaf898964ec7dadcd3bf78de9c165dec786fcdc0c00fc1daff
Build step 'Execute shell' marked build as failure