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/05/09 11:35:19 UTC
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3634
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3634/display/redirect?page=changes>
Changes:
[alexr] Removed the "WIP" prefix from 1.1.2 CHANGELOG.
[yujie.jay] Changed `#elif ...` to `#elif defined(...)` in Mesos.
[yujie.jay] Fixed provisioner recover blockage by non-existing rootfses dir.
[yujie.jay] Added MESOS-7471 to 1.1.2 CHANGELOG.
------------------------------------------
[...truncated 21.80 MB...]
I0509 11:34:37.125139 30425 recover.cpp:477] Replica is in STARTING status
I0509 11:34:37.126909 30436 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from __req_res__(6217)@172.17.0.2:50891
I0509 11:34:37.127277 30430 recover.cpp:197] Received a recover response from a replica in STARTING status
I0509 11:34:37.127893 30426 recover.cpp:568] Updating replica status to VOTING
I0509 11:34:37.162312 30429 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 34.200704ms
I0509 11:34:37.162395 30429 replica.cpp:320] Persisted replica status to VOTING
I0509 11:34:37.162617 30429 recover.cpp:582] Successfully joined the Paxos group
I0509 11:34:37.162930 30429 recover.cpp:466] Recover process terminated
I0509 11:34:37.163332 30435 log.cpp:553] Attempting to start the writer
I0509 11:34:37.164726 30425 replica.cpp:493] Replica received implicit promise request from __req_res__(6218)@172.17.0.2:50891 with proposal 1
I0509 11:34:37.191834 30425 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 27.068706ms
I0509 11:34:37.191880 30425 replica.cpp:342] Persisted promised to 1
I0509 11:34:37.192458 30431 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0509 11:34:37.193758 30429 replica.cpp:388] Replica received explicit promise request from __req_res__(6219)@172.17.0.2:50891 for position 0 with proposal 2
I0509 11:34:37.222725 30429 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 28.917584ms
I0509 11:34:37.222777 30429 replica.cpp:708] Persisted action NOP at position 0
I0509 11:34:37.223834 30429 replica.cpp:537] Replica received write request for position 0 from __req_res__(6220)@172.17.0.2:50891
I0509 11:34:37.223902 30429 leveldb.cpp:436] Reading position from leveldb took 34814ns
I0509 11:34:37.272874 30429 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 48.917416ms
I0509 11:34:37.272925 30429 replica.cpp:708] Persisted action NOP at position 0
I0509 11:34:37.273525 30435 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0509 11:34:37.297945 30435 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 24.375493ms
I0509 11:34:37.298004 30435 replica.cpp:708] Persisted action NOP at position 0
I0509 11:34:37.298620 30438 log.cpp:569] Writer started with ending position 0
I0509 11:34:37.299782 30439 leveldb.cpp:436] Reading position from leveldb took 47390ns
I0509 11:34:37.300786 30430 registrar.cpp:362] Successfully fetched the registry (0B) in 204.614656ms
I0509 11:34:37.300907 30430 registrar.cpp:461] Applied 1 operations in 22859ns; attempting to update the registry
I0509 11:34:37.301650 30428 log.cpp:577] Attempting to append 168 bytes to the log
I0509 11:34:37.301780 30433 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0509 11:34:37.302498 30431 replica.cpp:537] Replica received write request for position 1 from __req_res__(6221)@172.17.0.2:50891
I0509 11:34:37.323148 30431 leveldb.cpp:341] Persisting action (187 bytes) to leveldb took 20.597373ms
I0509 11:34:37.323231 30431 replica.cpp:708] Persisted action APPEND at position 1
I0509 11:34:37.324251 30438 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0509 11:34:37.348294 30438 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 23.99431ms
I0509 11:34:37.348382 30438 replica.cpp:708] Persisted action APPEND at position 1
I0509 11:34:37.349737 30436 registrar.cpp:506] Successfully updated the registry in 48.751104ms
I0509 11:34:37.349905 30436 registrar.cpp:392] Successfully recovered registrar
I0509 11:34:37.350006 30438 log.cpp:596] Attempting to truncate the log to 1
I0509 11:34:37.350201 30438 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0509 11:34:37.350445 30436 master.cpp:1676] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0509 11:34:37.350587 30433 hierarchical.cpp:176] Skipping recovery of hierarchical allocator: nothing to recover
I0509 11:34:37.354022 30424 replica.cpp:537] Replica received write request for position 2 from __req_res__(6222)@172.17.0.2:50891
I0509 11:34:37.375998 30424 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 21.885415ms
I0509 11:34:37.376098 30424 replica.cpp:708] Persisted action TRUNCATE at position 2
I0509 11:34:37.377311 30434 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0509 11:34:37.398581 30434 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 21.063749ms
I0509 11:34:37.398835 30434 leveldb.cpp:399] Deleting ~1 keys from leveldb took 93239ns
I0509 11:34:37.398895 30434 replica.cpp:708] Persisted action TRUNCATE at position 2
I0509 11:34:37.407181 30432 slave.cpp:208] Mesos agent started on 172.17.0.2:50891
I0509 11:34:37.407778 30405 scheduler.cpp:176] Version: 1.1.2
I0509 11:34:37.407224 30432 slave.cpp:209] 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/SlaveTest_KillTaskGroupBetweenRunTaskParts_xWwZhS/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/SlaveTest_KillTaskGroupBetweenRunTaskParts_xWwZhS/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/SlaveTest_KillTaskGroupBetweenRunTaskParts_xWwZhS/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.1.2/_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/SlaveTest_KillTaskGroupBetweenRunTaskParts_xWwZhS" --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/SlaveTest_KillTaskGroupBetweenRunTaskParts_aUfNap"
I0509 11:34:37.407897 30432 credentials.hpp:86] Loading credential for authentication from '/tmp/SlaveTest_KillTaskGroupBetweenRunTaskParts_xWwZhS/credential'
I0509 11:34:37.408109 30432 slave.cpp:346] Agent using credential for: test-principal
I0509 11:34:37.408131 30432 credentials.hpp:37] Loading credentials for authentication from '/tmp/SlaveTest_KillTaskGroupBetweenRunTaskParts_xWwZhS/http_credentials'
I0509 11:34:37.408432 30432 http.cpp:887] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0509 11:34:37.408574 30432 http.cpp:887] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0509 11:34:37.410213 30430 scheduler.cpp:465] New master detected at master@172.17.0.2:50891
I0509 11:34:37.410271 30430 scheduler.cpp:474] Waiting for 0ns before initiating a re-(connection) attempt with the master
I0509 11:34:37.410365 30432 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0509 11:34:37.410480 30432 slave.cpp:541] Agent attributes: [ ]
I0509 11:34:37.410504 30432 slave.cpp:546] Agent hostname: c8d7080919cd
I0509 11:34:37.412778 30436 state.cpp:57] Recovering state from '/tmp/SlaveTest_KillTaskGroupBetweenRunTaskParts_aUfNap/meta'
I0509 11:34:37.413163 30426 status_update_manager.cpp:203] Recovering status update manager
I0509 11:34:37.413537 30434 slave.cpp:5281] Finished recovery
I0509 11:34:37.414108 30434 slave.cpp:5455] Querying resource estimator for oversubscribable resources
I0509 11:34:37.414429 30430 status_update_manager.cpp:177] Pausing sending status updates
I0509 11:34:37.414453 30439 slave.cpp:915] New master detected at master@172.17.0.2:50891
I0509 11:34:37.414489 30439 slave.cpp:974] Authenticating with master master@172.17.0.2:50891
I0509 11:34:37.414566 30439 slave.cpp:985] Using default CRAM-MD5 authenticatee
I0509 11:34:37.414731 30439 slave.cpp:947] Detecting new master
I0509 11:34:37.414835 30431 authenticatee.cpp:121] Creating new client SASL connection
I0509 11:34:37.415172 30429 master.cpp:6680] Authenticating (83)@172.17.0.2:50891
I0509 11:34:37.415356 30429 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(968)@172.17.0.2:50891
I0509 11:34:37.415691 30429 authenticator.cpp:98] Creating new server SASL connection
I0509 11:34:37.415843 30438 scheduler.cpp:353] Connected with the master at http://172.17.0.2:50891/master/api/v1/scheduler
I0509 11:34:37.416039 30429 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0509 11:34:37.416069 30429 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0509 11:34:37.416225 30438 authenticator.cpp:204] Received SASL authentication start
I0509 11:34:37.416301 30438 authenticator.cpp:326] Authentication requires more steps
I0509 11:34:37.416498 30438 authenticatee.cpp:259] Received SASL authentication step
I0509 11:34:37.416743 30438 authenticator.cpp:232] Received SASL authentication step
I0509 11:34:37.416851 30438 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'c8d7080919cd' server FQDN: 'c8d7080919cd' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0509 11:34:37.416879 30438 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0509 11:34:37.416932 30438 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0509 11:34:37.416975 30438 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'c8d7080919cd' server FQDN: 'c8d7080919cd' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0509 11:34:37.416998 30438 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0509 11:34:37.417016 30438 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0509 11:34:37.417063 30438 authenticator.cpp:318] Authentication success
I0509 11:34:37.417243 30433 authenticatee.cpp:299] Authentication success
I0509 11:34:37.417284 30427 master.cpp:6710] Successfully authenticated principal 'test-principal' at (83)@172.17.0.2:50891
I0509 11:34:37.417290 30436 scheduler.cpp:235] Sending SUBSCRIBE call to http://172.17.0.2:50891/master/api/v1/scheduler
I0509 11:34:37.417286 30438 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(968)@172.17.0.2:50891
I0509 11:34:37.418334 30435 process.cpp:3377] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0509 11:34:37.418473 30439 slave.cpp:1069] Successfully authenticated with master master@172.17.0.2:50891
I0509 11:34:37.418643 30439 slave.cpp:1475] Will retry registration in 13.967579ms if necessary
I0509 11:34:37.419026 30435 master.cpp:5103] Registering agent at (83)@172.17.0.2:50891 (c8d7080919cd) with id c71237fd-ebe4-440f-a93e-5475eded5529-S0
I0509 11:34:37.419472 30425 registrar.cpp:461] Applied 1 operations in 58911ns; attempting to update the registry
I0509 11:34:37.419587 30426 http.cpp:391] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:40388
I0509 11:34:37.419880 30426 master.cpp:2313] Received subscription request for HTTP framework 'default'
I0509 11:34:37.419997 30426 master.cpp:2053] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0509 11:34:37.420176 30430 log.cpp:577] Attempting to append 337 bytes to the log
I0509 11:34:37.420455 30430 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 3
I0509 11:34:37.420943 30425 master.cpp:2411] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0509 11:34:37.421674 30430 hierarchical.cpp:275] Added framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.421752 30430 hierarchical.cpp:1694] No allocations performed
I0509 11:34:37.421787 30430 hierarchical.cpp:1789] No inverse offers to send out!
I0509 11:34:37.421855 30430 hierarchical.cpp:1286] Performed allocation for 0 agents in 157101ns
I0509 11:34:37.421967 30430 master.hpp:2163] Sending heartbeat to c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.422181 30430 replica.cpp:537] Replica received write request for position 3 from __req_res__(6223)@172.17.0.2:50891
I0509 11:34:37.423655 30427 scheduler.cpp:671] Enqueuing event SUBSCRIBED received from http://172.17.0.2:50891/master/api/v1/scheduler
I0509 11:34:37.424216 30427 scheduler.cpp:671] Enqueuing event HEARTBEAT received from http://172.17.0.2:50891/master/api/v1/scheduler
I0509 11:34:37.433912 30435 slave.cpp:1475] Will retry registration in 36.893601ms if necessary
I0509 11:34:37.434059 30432 master.cpp:5091] Ignoring register agent message from (83)@172.17.0.2:50891 (c8d7080919cd) as admission is already in progress
I0509 11:34:37.457453 30430 leveldb.cpp:341] Persisting action (356 bytes) to leveldb took 35.200376ms
I0509 11:34:37.457546 30430 replica.cpp:708] Persisted action APPEND at position 3
I0509 11:34:37.458478 30438 replica.cpp:691] Replica received learned notice for position 3 from @0.0.0.0:0
I0509 11:34:37.471690 30430 slave.cpp:1475] Will retry registration in 38.755116ms if necessary
I0509 11:34:37.471881 30427 master.cpp:5091] Ignoring register agent message from (83)@172.17.0.2:50891 (c8d7080919cd) as admission is already in progress
I0509 11:34:37.482503 30438 leveldb.cpp:341] Persisting action (358 bytes) to leveldb took 23.8981ms
I0509 11:34:37.482578 30438 replica.cpp:708] Persisted action APPEND at position 3
I0509 11:34:37.484477 30429 registrar.cpp:506] Successfully updated the registry in 64.900096ms
I0509 11:34:37.484715 30427 log.cpp:596] Attempting to truncate the log to 3
I0509 11:34:37.484848 30425 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 4
I0509 11:34:37.485659 30435 master.cpp:5174] Registered agent c71237fd-ebe4-440f-a93e-5475eded5529-S0 at (83)@172.17.0.2:50891 (c8d7080919cd) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0509 11:34:37.486027 30434 hierarchical.cpp:485] Added agent c71237fd-ebe4-440f-a93e-5475eded5529-S0 (c8d7080919cd) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0509 11:34:37.486593 30428 replica.cpp:537] Replica received write request for position 4 from __req_res__(6224)@172.17.0.2:50891
I0509 11:34:37.487938 30434 hierarchical.cpp:1789] No inverse offers to send out!
I0509 11:34:37.488072 30434 hierarchical.cpp:1309] Performed allocation for agent c71237fd-ebe4-440f-a93e-5475eded5529-S0 in 2.003269ms
I0509 11:34:37.488173 30425 slave.cpp:4133] Received ping from slave-observer(434)@172.17.0.2:50891
I0509 11:34:37.488353 30433 master.cpp:6509] Sending 1 offers to framework c71237fd-ebe4-440f-a93e-5475eded5529-0000 (default)
I0509 11:34:37.488378 30425 slave.cpp:1115] Registered with master master@172.17.0.2:50891; given agent ID c71237fd-ebe4-440f-a93e-5475eded5529-S0
I0509 11:34:37.488404 30425 fetcher.cpp:90] Clearing fetcher cache
I0509 11:34:37.488637 30437 status_update_manager.cpp:184] Resuming sending status updates
I0509 11:34:37.488857 30425 slave.cpp:1138] Checkpointing SlaveInfo to '/tmp/SlaveTest_KillTaskGroupBetweenRunTaskParts_aUfNap/meta/slaves/c71237fd-ebe4-440f-a93e-5475eded5529-S0/slave.info'
I0509 11:34:37.490201 30430 scheduler.cpp:671] Enqueuing event OFFERS received from http://172.17.0.2:50891/master/api/v1/scheduler
I0509 11:34:37.492532 30425 scheduler.cpp:235] Sending ACCEPT call to http://172.17.0.2:50891/master/api/v1/scheduler
I0509 11:34:37.493407 30435 process.cpp:3377] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0509 11:34:37.494307 30431 http.cpp:391] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:40387
I0509 11:34:37.495142 30431 master.cpp:3554] Processing ACCEPT call for offers: [ c71237fd-ebe4-440f-a93e-5475eded5529-O0 ] on agent c71237fd-ebe4-440f-a93e-5475eded5529-S0 at (83)@172.17.0.2:50891 (c8d7080919cd) for framework c71237fd-ebe4-440f-a93e-5475eded5529-0000 (default)
I0509 11:34:37.495246 30431 master.cpp:3159] Authorizing framework principal 'test-principal' to launch task ebf3ec17-2aac-4255-912b-d698edf3497c
I0509 11:34:37.495543 30431 master.cpp:3159] Authorizing framework principal 'test-principal' to launch task 3a046b3e-771d-4d12-a3de-65ec857f6a51
I0509 11:34:37.498735 30431 master.cpp:8262] Adding task ebf3ec17-2aac-4255-912b-d698edf3497c with resources cpus(*):0.1; mem(*):32; disk(*):32 on agent c71237fd-ebe4-440f-a93e-5475eded5529-S0 (c8d7080919cd)
I0509 11:34:37.499084 30431 master.cpp:8262] Adding task 3a046b3e-771d-4d12-a3de-65ec857f6a51 with resources cpus(*):0.1; mem(*):32; disk(*):32 on agent c71237fd-ebe4-440f-a93e-5475eded5529-S0 (c8d7080919cd)
I0509 11:34:37.499281 30431 master.cpp:4397] Launching task group { 3a046b3e-771d-4d12-a3de-65ec857f6a51, ebf3ec17-2aac-4255-912b-d698edf3497c } of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000 (default) with resources cpus(*):0.2; mem(*):64; disk(*):64 on agent c71237fd-ebe4-440f-a93e-5475eded5529-S0 at (83)@172.17.0.2:50891 (c8d7080919cd)
I0509 11:34:37.499797 30438 slave.cpp:1539] Got assigned task group containing tasks [ ebf3ec17-2aac-4255-912b-d698edf3497c, 3a046b3e-771d-4d12-a3de-65ec857f6a51 ] for framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.500180 30424 hierarchical.cpp:1018] Recovered cpus(*):1.7; mem(*):928; disk(*):928; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):0.3; mem(*):96; disk(*):96) on agent c71237fd-ebe4-440f-a93e-5475eded5529-S0 from framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.500241 30424 hierarchical.cpp:1055] Framework c71237fd-ebe4-440f-a93e-5475eded5529-0000 filtered agent c71237fd-ebe4-440f-a93e-5475eded5529-S0 for 5secs
I0509 11:34:37.501157 30433 scheduler.cpp:235] Sending KILL call to http://172.17.0.2:50891/master/api/v1/scheduler
I0509 11:34:37.501938 30429 process.cpp:3377] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0509 11:34:37.502636 30433 http.cpp:391] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:40387
I0509 11:34:37.502780 30433 master.cpp:4711] Telling agent c71237fd-ebe4-440f-a93e-5475eded5529-S0 at (83)@172.17.0.2:50891 (c8d7080919cd) to kill task ebf3ec17-2aac-4255-912b-d698edf3497c of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000 (default)
I0509 11:34:37.502939 30438 slave.cpp:2288] Asked to kill task ebf3ec17-2aac-4255-912b-d698edf3497c of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
W0509 11:34:37.502985 30438 slave.cpp:2329] Killing task ebf3ec17-2aac-4255-912b-d698edf3497c of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000 before it was launched
I0509 11:34:37.503576 30405 slave.cpp:1701] Launching task group containing tasks [ ebf3ec17-2aac-4255-912b-d698edf3497c, 3a046b3e-771d-4d12-a3de-65ec857f6a51 ] for framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
W0509 11:34:37.503720 30405 slave.cpp:1741] Ignoring running task group containing tasks [ ebf3ec17-2aac-4255-912b-d698edf3497c, 3a046b3e-771d-4d12-a3de-65ec857f6a51 ] of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000 because it has been killed in the meantime
I0509 11:34:37.503906 30405 slave.cpp:3634] Handling status update TASK_KILLED (UUID: fb77f9e1-c49c-4acb-a72e-e7df74ef8836) for task ebf3ec17-2aac-4255-912b-d698edf3497c of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000 from @0.0.0.0:0
W0509 11:34:37.503978 30405 slave.cpp:3730] Could not find the executor for status update TASK_KILLED (UUID: fb77f9e1-c49c-4acb-a72e-e7df74ef8836) for task ebf3ec17-2aac-4255-912b-d698edf3497c of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.504264 30436 status_update_manager.cpp:323] Received status update TASK_KILLED (UUID: fb77f9e1-c49c-4acb-a72e-e7df74ef8836) for task ebf3ec17-2aac-4255-912b-d698edf3497c of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.504349 30436 status_update_manager.cpp:500] Creating StatusUpdate stream for task ebf3ec17-2aac-4255-912b-d698edf3497c of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.504300 30405 slave.cpp:3634] Handling status update TASK_KILLED (UUID: 7975a98d-1451-446e-9882-65c92413f61e) for task 3a046b3e-771d-4d12-a3de-65ec857f6a51 of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000 from @0.0.0.0:0
W0509 11:34:37.504431 30405 slave.cpp:3730] Could not find the executor for status update TASK_KILLED (UUID: 7975a98d-1451-446e-9882-65c92413f61e) for task 3a046b3e-771d-4d12-a3de-65ec857f6a51 of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.504688 30405 slave.cpp:4734] Cleaning up framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.504755 30436 status_update_manager.cpp:377] Forwarding update TASK_KILLED (UUID: fb77f9e1-c49c-4acb-a72e-e7df74ef8836) for task ebf3ec17-2aac-4255-912b-d698edf3497c of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000 to the agent
E0509 11:34:37.504827 30405 slave.cpp:5435] Failed to find the mtime of '/tmp/SlaveTest_KillTaskGroupBetweenRunTaskParts_aUfNap/slaves/c71237fd-ebe4-440f-a93e-5475eded5529-S0/frameworks/c71237fd-ebe4-440f-a93e-5475eded5529-0000': Error invoking stat for '/tmp/SlaveTest_KillTaskGroupBetweenRunTaskParts_aUfNap/slaves/c71237fd-ebe4-440f-a93e-5475eded5529-S0/frameworks/c71237fd-ebe4-440f-a93e-5475eded5529-0000': No such file or directory
I0509 11:34:37.504978 30430 slave.cpp:4051] Forwarding the update TASK_KILLED (UUID: fb77f9e1-c49c-4acb-a72e-e7df74ef8836) for task ebf3ec17-2aac-4255-912b-d698edf3497c of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000 to master@172.17.0.2:50891
I0509 11:34:37.505023 30436 status_update_manager.cpp:323] Received status update TASK_KILLED (UUID: 7975a98d-1451-446e-9882-65c92413f61e) for task 3a046b3e-771d-4d12-a3de-65ec857f6a51 of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.505067 30436 status_update_manager.cpp:500] Creating StatusUpdate stream for task 3a046b3e-771d-4d12-a3de-65ec857f6a51 of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.505131 30430 slave.cpp:3945] Status update manager successfully handled status update TASK_KILLED (UUID: fb77f9e1-c49c-4acb-a72e-e7df74ef8836) for task ebf3ec17-2aac-4255-912b-d698edf3497c of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.505304 30431 master.cpp:5707] Status update TASK_KILLED (UUID: fb77f9e1-c49c-4acb-a72e-e7df74ef8836) for task ebf3ec17-2aac-4255-912b-d698edf3497c of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000 from agent c71237fd-ebe4-440f-a93e-5475eded5529-S0 at (83)@172.17.0.2:50891 (c8d7080919cd)
I0509 11:34:37.505360 30431 master.cpp:5769] Forwarding status update TASK_KILLED (UUID: fb77f9e1-c49c-4acb-a72e-e7df74ef8836) for task ebf3ec17-2aac-4255-912b-d698edf3497c of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.505383 30436 status_update_manager.cpp:377] Forwarding update TASK_KILLED (UUID: 7975a98d-1451-446e-9882-65c92413f61e) for task 3a046b3e-771d-4d12-a3de-65ec857f6a51 of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000 to the agent
I0509 11:34:37.505602 30437 slave.cpp:4051] Forwarding the update TASK_KILLED (UUID: 7975a98d-1451-446e-9882-65c92413f61e) for task 3a046b3e-771d-4d12-a3de-65ec857f6a51 of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000 to master@172.17.0.2:50891
I0509 11:34:37.505659 30431 master.cpp:7640] Updating the state of task ebf3ec17-2aac-4255-912b-d698edf3497c of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0509 11:34:37.505667 30436 status_update_manager.cpp:285] Closing status update streams for framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.505792 30436 status_update_manager.cpp:531] Cleaning up status update stream for task 3a046b3e-771d-4d12-a3de-65ec857f6a51 of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.505844 30437 slave.cpp:3945] Status update manager successfully handled status update TASK_KILLED (UUID: 7975a98d-1451-446e-9882-65c92413f61e) for task 3a046b3e-771d-4d12-a3de-65ec857f6a51 of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.505970 30436 status_update_manager.cpp:531] Cleaning up status update stream for task ebf3ec17-2aac-4255-912b-d698edf3497c of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.506196 30431 master.cpp:5707] Status update TASK_KILLED (UUID: 7975a98d-1451-446e-9882-65c92413f61e) for task 3a046b3e-771d-4d12-a3de-65ec857f6a51 of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000 from agent c71237fd-ebe4-440f-a93e-5475eded5529-S0 at (83)@172.17.0.2:50891 (c8d7080919cd)
I0509 11:34:37.506243 30431 master.cpp:5769] Forwarding status update TASK_KILLED (UUID: 7975a98d-1451-446e-9882-65c92413f61e) for task 3a046b3e-771d-4d12-a3de-65ec857f6a51 of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.506273 30430 hierarchical.cpp:1018] Recovered cpus(*):0.1; mem(*):32; disk(*):32 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):0.2; mem(*):64; disk(*):64) on agent c71237fd-ebe4-440f-a93e-5475eded5529-S0 from framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.506443 30431 master.cpp:7640] Updating the state of task 3a046b3e-771d-4d12-a3de-65ec857f6a51 of framework c71237fd-ebe4-440f-a93e-5475eded5529-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0509 11:34:37.506877 30427 hierarchical.cpp:1018] Recovered cpus(*):0.1; mem(*):32; disk(*):32 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):0.1; mem(*):32; disk(*):32) on agent c71237fd-ebe4-440f-a93e-5475eded5529-S0 from framework c71237fd-ebe4-440f-a93e-5475eded5529-0000
I0509 11:34:37.507390 30426 scheduler.cpp:671] Enqueuing event UPDATE received from http://172.17.0.2:50891/master/api/v1/scheduler
I0509 11:34:37.507928 30426 scheduler.cpp:671] Enqueuing event UPDATE received from http://172.17.0.2:50891/master/api/v1/scheduler
I0509 11:34:37.509778 30437 slave.cpp:787] Agent terminating
*** Aborted at 1494329677 (unix time) try "date -d @1494329677" if you are using GNU date ***
I0509 11:34:37.516649 30428 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 29.976487ms
I0509 11:34:37.516736 30428 replica.cpp:708] Persisted action TRUNCATE at position 4
I0509 11:34:37.517720 30436 replica.cpp:691] Replica received learned notice for position 4 from @0.0.0.0:0
PC: @ 0x7fe365bfa8f6 mesos::FrameworkInfo::checkpoint()
*** SIGSEGV (@0x88) received by PID 30405 (TID 0x7fe3530cc700) from PID 136; stack trace: ***
@ 0x7fe30b767fc5 (unknown)
@ 0x7fe30b76d2c1 (unknown)
@ 0x7fe30b7610a8 (unknown)
@ 0x7fe361877370 (unknown)
@ 0x7fe365bfa8f6 mesos::FrameworkInfo::checkpoint()
@ 0x7fe366079f94 mesos::internal::slave::Slave::finalize()
I0509 11:34:37.550155 30436 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 32.371822ms
I0509 11:34:37.550290 30436 leveldb.cpp:399] Deleting ~2 keys from leveldb took 60459ns
I0509 11:34:37.550317 30436 replica.cpp:708] Persisted action TRUNCATE at position 4
@ 0x7fe366cb31ff process::ProcessBase::visit()
@ 0x7fe366cb8e2c process::TerminateEvent::visit()
@ 0xa1eb94 process::ProcessBase::serve()
@ 0x7fe366cacf3f process::ProcessManager::resume()
@ 0x7fe366ca9c2d _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv
@ 0x7fe366cb84d0 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x7fe366cb8427 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv
@ 0x7fe366cb83c0 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x7fe361208230 (unknown)
@ 0x7fe36186fdc5 start_thread
@ 0x7fe36097173d __clone
make[4]: *** [check-local] Segmentation fault
make[4]: Leaving directory `/mesos/mesos-1.1.2/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.1.2/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.1.2/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.1.2/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1494327123-741
Untagged: mesos-1494327123-741:latest
Deleted: sha256:157ea05b6e984ba8b0a90b17a973f84809b71427110066904a11133dad46ae90
Deleted: sha256:f86f46771ba52dda52f3e2dae2532b0ed6948cc7ac13b091edb1b298dfcb8f07
Deleted: sha256:39f33ef63f353ba35335c30b06a8c61d3046acd6f6c0ca031b2360bdb20da76a
Deleted: sha256:1bce7848035180cd012a5cc094ec6b094b5c9a0d4bf8ebe94e51f60c28bffa5d
Deleted: sha256:87b52d09d81e24009f99bb4bdf627b088792fa8c18c3e180c32bc3c7fc7b7038
Deleted: sha256:dfa0e9b5f941746f9fa66794c556ee1abc878c81516c6e6cf74718b03eb5800b
Deleted: sha256:a577baa5fdcec2f395357cfe8c11059a61575d0a8a6934c498e2c46dcfcff3e4
Deleted: sha256:2105dc8bccb26c479cfda1faf59a701ca00f9c6604b23dd77693b93d29a105ee
Deleted: sha256:31c2e195f591f61ca71bc569b1ac68a594f6fb5a2f285a5841c8c740c653c808
Deleted: sha256:74cd04bf0e08b4f7d6b47ce76fe573a8f156798d7403621cf982e0b55518ec22
Deleted: sha256:1d5a324bdae47468f14222a9e605055fb4b95c23fdc98850959c1cbd1b654370
Deleted: sha256:7d2b5887d50919329d397956665e8eb21a0b559b08b0e4bac0015121731a1631
Deleted: sha256:b4e868f86f7cd94c8dcb12375568927e662d57e9320f1d834bfd2510e868ef76
Deleted: sha256:6041006787fdd69d3452b63ecaa43da8d8ac389de259964d09e2c3cd8671d816
Deleted: sha256:a547b27c977eae82bdaffcb79e215c2b5c54f3175fcc1e1be4d97596ea1d1cfe
Deleted: sha256:8ebc0052907f44593ef2da4eb881d8cd66a52c72903588febea05e3edcfa0c11
Deleted: sha256:2c953e1537ac2e557adef4875576fb72150239860df2e7035ae604db06b63c50
Deleted: sha256:c206058ab30a113da217d623c95b6741dc496bd01bc4bd79e9c48fa2a3430f2d
Deleted: sha256:e6b138a79cbddc751b4c2e5a3299e27f78ab1a3cd7d26f9a6a45f23140f120c8
Deleted: sha256:b2857344716616df77bd71d1955e7a2cde884ac975ee0a16b6fb1cdbbf96094c
Deleted: sha256:892c4182924c6a1065932f031ae0d12f7d377fab8066fb7e370300d7208835d9
Deleted: sha256:9e6ac1ea13fdaf8f0f7b0a20d3c6856544f64ec3711f47c4d47ccd23086ea233
Deleted: sha256:bfa884107f7ceadddd0691150f25003330c6fcc1824404eb72b0de800a59d3f4
Deleted: sha256:c98f49f53658d18653142c32dd20d6842e869fe92b56992939e0c90ff82e6441
Deleted: sha256:5064b49b0f8122638ec63693c3b9f95bec56d95a7d9b40e862de6c9398e13254
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user yujie.jay@gmail.com
Jenkins build is back to normal : Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3635
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3635/display/redirect?page=changes>