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 2016/02/29 08:52:32 UTC
Build failed in Jenkins: Mesos » gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1) #1745
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1745/changes>
Changes:
[mpark] Added full reserved resource info to `/slaves` master endpoint.
------------------------------------------
[...truncated 161095 lines...]
I0229 07:52:28.483371 1976 replica.cpp:537] Replica received write request for position 3 from (14513)@172.17.0.2:36666
I0229 07:52:28.484046 1976 leveldb.cpp:341] Persisting action (358 bytes) to leveldb took 610626ns
I0229 07:52:28.484078 1976 replica.cpp:712] Persisted action at 3
I0229 07:52:28.484736 1982 replica.cpp:691] Replica received learned notice for position 3 from @0.0.0.0:0
I0229 07:52:28.485183 1982 leveldb.cpp:341] Persisting action (360 bytes) to leveldb took 402216ns
I0229 07:52:28.485215 1982 replica.cpp:712] Persisted action at 3
I0229 07:52:28.485239 1982 replica.cpp:697] Replica learned APPEND action at position 3
I0229 07:52:28.486762 1977 registrar.cpp:484] Successfully updated the 'registry' in 6.011904ms
I0229 07:52:28.487078 1980 log.cpp:702] Attempting to truncate the log to 3
I0229 07:52:28.487350 1979 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 4
I0229 07:52:28.487555 1971 slave.cpp:1321] Will retry registration in 20.849896ms if necessary
I0229 07:52:28.487972 1975 slave.cpp:3482] Received ping from slave-observer(406)@172.17.0.2:36666
I0229 07:52:28.488133 1969 master.cpp:4322] Registered slave e8206980-7279-4f85-8625-302d893eeda3-S0 at slave(438)@172.17.0.2:36666 (09af0089f551) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0229 07:52:28.488344 1967 replica.cpp:537] Replica received write request for position 4 from (14514)@172.17.0.2:36666
I0229 07:52:28.488344 1975 slave.cpp:971] Registered with master master@172.17.0.2:36666; given slave ID e8206980-7279-4f85-8625-302d893eeda3-S0
I0229 07:52:28.488468 1975 fetcher.cpp:81] Clearing fetcher cache
I0229 07:52:28.488384 1973 hierarchical.cpp:473] Added slave e8206980-7279-4f85-8625-302d893eeda3-S0 (09af0089f551) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: )
I0229 07:52:28.488608 1982 status_update_manager.cpp:181] Resuming sending status updates
I0229 07:52:28.488680 1969 master.cpp:4224] Slave e8206980-7279-4f85-8625-302d893eeda3-S0 at slave(438)@172.17.0.2:36666 (09af0089f551) already registered, resending acknowledgement
I0229 07:52:28.489011 1975 slave.cpp:994] Checkpointing SlaveInfo to '/tmp/ContentType_SchedulerTest_TaskRunning_1_qU7Znf/meta/slaves/e8206980-7279-4f85-8625-302d893eeda3-S0/slave.info'
I0229 07:52:28.489043 1967 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 565336ns
I0229 07:52:28.489071 1967 replica.cpp:712] Persisted action at 4
I0229 07:52:28.489405 1975 slave.cpp:1030] Forwarding total oversubscribed resources
I0229 07:52:28.489485 1973 hierarchical.cpp:1532] No inverse offers to send out!
I0229 07:52:28.489529 1973 hierarchical.cpp:1150] Performed allocation for slave e8206980-7279-4f85-8625-302d893eeda3-S0 in 929901ns
W0229 07:52:28.489562 1975 slave.cpp:1016] Already registered with master master@172.17.0.2:36666
I0229 07:52:28.489586 1975 slave.cpp:1030] Forwarding total oversubscribed resources
I0229 07:52:28.489975 1975 master.cpp:5369] Sending 1 offers to framework e8206980-7279-4f85-8625-302d893eeda3-0000 (default)
I0229 07:52:28.490113 1980 replica.cpp:691] Replica received learned notice for position 4 from @0.0.0.0:0
I0229 07:52:28.490680 1980 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 449914ns
I0229 07:52:28.490834 1980 leveldb.cpp:399] Deleting ~2 keys from leveldb took 127027ns
I0229 07:52:28.490861 1980 replica.cpp:712] Persisted action at 4
I0229 07:52:28.490908 1980 replica.cpp:697] Replica learned TRUNCATE action at position 4
I0229 07:52:28.491040 1975 master.cpp:4663] Received update of slave e8206980-7279-4f85-8625-302d893eeda3-S0 at slave(438)@172.17.0.2:36666 (09af0089f551) with total oversubscribed resources
I0229 07:52:28.491263 1975 master.cpp:4663] Received update of slave e8206980-7279-4f85-8625-302d893eeda3-S0 at slave(438)@172.17.0.2:36666 (09af0089f551) with total oversubscribed resources
I0229 07:52:28.492225 1980 hierarchical.cpp:531] Slave e8206980-7279-4f85-8625-302d893eeda3-S0 (09af0089f551) updated with oversubscribed resources (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000])
I0229 07:52:28.492401 1980 hierarchical.cpp:1437] No resources available to allocate!
I0229 07:52:28.492449 1980 hierarchical.cpp:1532] No inverse offers to send out!
I0229 07:52:28.492480 1980 hierarchical.cpp:1150] Performed allocation for slave e8206980-7279-4f85-8625-302d893eeda3-S0 in 189320ns
I0229 07:52:28.492988 1980 hierarchical.cpp:531] Slave e8206980-7279-4f85-8625-302d893eeda3-S0 (09af0089f551) updated with oversubscribed resources (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000])
I0229 07:52:28.493146 1980 hierarchical.cpp:1437] No resources available to allocate!
I0229 07:52:28.493204 1980 hierarchical.cpp:1532] No inverse offers to send out!
I0229 07:52:28.493232 1980 hierarchical.cpp:1150] Performed allocation for slave e8206980-7279-4f85-8625-302d893eeda3-S0 in 183570ns
I0229 07:52:28.493556 1971 scheduler.cpp:602] Enqueuing event OFFERS received from http://172.17.0.2:36666/master/api/v1/scheduler
I0229 07:52:28.495486 1979 scheduler.cpp:230] Sending ACCEPT call to http://172.17.0.2:36666/master/api/v1/scheduler
I0229 07:52:28.497033 1980 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0229 07:52:28.497614 1969 http.cpp:311] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:33773
I0229 07:52:28.500296 1969 master.cpp:3152] Processing ACCEPT call for offers: [ e8206980-7279-4f85-8625-302d893eeda3-O0 ] on slave e8206980-7279-4f85-8625-302d893eeda3-S0 at slave(438)@172.17.0.2:36666 (09af0089f551) for framework e8206980-7279-4f85-8625-302d893eeda3-0000 (default)
I0229 07:52:28.500342 1969 master.cpp:2824] Authorizing framework principal 'test-principal' to launch task 1 as user 'mesos'
W0229 07:52:28.501679 1969 validation.cpp:404] Executor default for task 1 uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0229 07:52:28.501719 1969 validation.cpp:416] Executor default for task 1 uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0229 07:52:28.502074 1969 master.hpp:176] Adding task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave e8206980-7279-4f85-8625-302d893eeda3-S0 (09af0089f551)
I0229 07:52:28.502351 1969 master.cpp:3637] Launching task 1 of framework e8206980-7279-4f85-8625-302d893eeda3-0000 (default) with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave e8206980-7279-4f85-8625-302d893eeda3-S0 at slave(438)@172.17.0.2:36666 (09af0089f551)
I0229 07:52:28.502774 1968 slave.cpp:1361] Got assigned task 1 for framework e8206980-7279-4f85-8625-302d893eeda3-0000
I0229 07:52:28.503413 1968 slave.cpp:1480] Launching task 1 for framework e8206980-7279-4f85-8625-302d893eeda3-0000
I0229 07:52:28.504015 1968 paths.cpp:474] Trying to chown '/tmp/ContentType_SchedulerTest_TaskRunning_1_qU7Znf/slaves/e8206980-7279-4f85-8625-302d893eeda3-S0/frameworks/e8206980-7279-4f85-8625-302d893eeda3-0000/executors/default/runs/29fd6e7a-d4a1-4e45-b51d-045b87e1b962' to user 'mesos'
I0229 07:52:28.513084 1968 slave.cpp:5367] Launching executor default of framework e8206980-7279-4f85-8625-302d893eeda3-0000 with resources in work directory '/tmp/ContentType_SchedulerTest_TaskRunning_1_qU7Znf/slaves/e8206980-7279-4f85-8625-302d893eeda3-S0/frameworks/e8206980-7279-4f85-8625-302d893eeda3-0000/executors/default/runs/29fd6e7a-d4a1-4e45-b51d-045b87e1b962'
I0229 07:52:28.515314 1968 executor.cpp:172] Version: 0.28.0
I0229 07:52:28.515817 1968 slave.cpp:1698] Queuing task '1' for executor 'default' of framework e8206980-7279-4f85-8625-302d893eeda3-0000
I0229 07:52:28.515980 1968 slave.cpp:749] Successfully attached file '/tmp/ContentType_SchedulerTest_TaskRunning_1_qU7Znf/slaves/e8206980-7279-4f85-8625-302d893eeda3-S0/frameworks/e8206980-7279-4f85-8625-302d893eeda3-0000/executors/default/runs/29fd6e7a-d4a1-4e45-b51d-045b87e1b962'
I0229 07:52:28.517381 1977 executor.cpp:316] Connected with the agent
I0229 07:52:28.517999 1981 executor.cpp:247] Sending SUBSCRIBE call to http://172.17.0.2:36666/slave(438)/api/v1/executor
I0229 07:52:28.518923 1979 process.cpp:3141] Handling HTTP event for process 'slave(438)' with path: '/slave(438)/api/v1/executor'
I0229 07:52:28.519599 1980 http.cpp:190] HTTP POST for /slave(438)/api/v1/executor from 172.17.0.2:33774
I0229 07:52:28.519843 1980 slave.cpp:2476] Received Subscribe request for HTTP executor 'default' of framework e8206980-7279-4f85-8625-302d893eeda3-0000
I0229 07:52:28.520802 1980 slave.cpp:1863] Sending queued task '1' to executor 'default' of framework e8206980-7279-4f85-8625-302d893eeda3-0000 (via HTTP)
I0229 07:52:28.522734 1973 executor.cpp:588] Enqueuing event SUBSCRIBED received from http://172.17.0.2:36666/slave(438)/api/v1/executor
I0229 07:52:28.523689 1967 executor.cpp:588] Enqueuing event LAUNCH received from http://172.17.0.2:36666/slave(438)/api/v1/executor
I0229 07:52:28.525502 1973 executor.cpp:247] Sending UPDATE call to http://172.17.0.2:36666/slave(438)/api/v1/executor
I0229 07:52:28.526407 1981 process.cpp:3141] Handling HTTP event for process 'slave(438)' with path: '/slave(438)/api/v1/executor'
I0229 07:52:28.527099 1969 http.cpp:190] HTTP POST for /slave(438)/api/v1/executor from 172.17.0.2:33775
I0229 07:52:28.527274 1969 slave.cpp:3002] Handling status update TASK_RUNNING (UUID: 4e8ba4e2-0907-4060-9446-f93b16dff5bb) for task 1 of framework e8206980-7279-4f85-8625-302d893eeda3-0000
I0229 07:52:28.528007 1971 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: 4e8ba4e2-0907-4060-9446-f93b16dff5bb) for task 1 of framework e8206980-7279-4f85-8625-302d893eeda3-0000
I0229 07:52:28.528062 1971 status_update_manager.cpp:497] Creating StatusUpdate stream for task 1 of framework e8206980-7279-4f85-8625-302d893eeda3-0000
I0229 07:52:28.528574 1971 status_update_manager.cpp:374] Forwarding update TASK_RUNNING (UUID: 4e8ba4e2-0907-4060-9446-f93b16dff5bb) for task 1 of framework e8206980-7279-4f85-8625-302d893eeda3-0000 to the slave
I0229 07:52:28.528873 1970 slave.cpp:3400] Forwarding the update TASK_RUNNING (UUID: 4e8ba4e2-0907-4060-9446-f93b16dff5bb) for task 1 of framework e8206980-7279-4f85-8625-302d893eeda3-0000 to master@172.17.0.2:36666
I0229 07:52:28.529063 1970 slave.cpp:3294] Status update manager successfully handled status update TASK_RUNNING (UUID: 4e8ba4e2-0907-4060-9446-f93b16dff5bb) for task 1 of framework e8206980-7279-4f85-8625-302d893eeda3-0000
I0229 07:52:28.529325 1968 master.cpp:4808] Status update TASK_RUNNING (UUID: 4e8ba4e2-0907-4060-9446-f93b16dff5bb) for task 1 of framework e8206980-7279-4f85-8625-302d893eeda3-0000 from slave e8206980-7279-4f85-8625-302d893eeda3-S0 at slave(438)@172.17.0.2:36666 (09af0089f551)
I0229 07:52:28.529394 1968 master.cpp:4856] Forwarding status update TASK_RUNNING (UUID: 4e8ba4e2-0907-4060-9446-f93b16dff5bb) for task 1 of framework e8206980-7279-4f85-8625-302d893eeda3-0000
I0229 07:52:28.530136 1978 executor.cpp:588] Enqueuing event ACKNOWLEDGED received from http://172.17.0.2:36666/slave(438)/api/v1/executor
I0229 07:52:28.530340 1968 master.cpp:6464] Updating the state of task 1 of framework e8206980-7279-4f85-8625-302d893eeda3-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0229 07:52:28.532052 1979 scheduler.cpp:602] Enqueuing event UPDATE received from http://172.17.0.2:36666/master/api/v1/scheduler
I0229 07:52:28.533727 1973 master.cpp:1026] Master terminating
W0229 07:52:28.534028 1973 master.cpp:6516] Removing task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework e8206980-7279-4f85-8625-302d893eeda3-0000 on slave e8206980-7279-4f85-8625-302d893eeda3-S0 at slave(438)@172.17.0.2:36666 (09af0089f551) in non-terminal state TASK_RUNNING
I0229 07:52:28.534420 1968 hierarchical.cpp:505] Removed slave e8206980-7279-4f85-8625-302d893eeda3-S0
I0229 07:52:28.534714 1973 master.cpp:6559] Removing executor 'default' with resources of framework e8206980-7279-4f85-8625-302d893eeda3-0000 on slave e8206980-7279-4f85-8625-302d893eeda3-S0 at slave(438)@172.17.0.2:36666 (09af0089f551)
I0229 07:52:28.535457 1975 hierarchical.cpp:326] Removed framework e8206980-7279-4f85-8625-302d893eeda3-0000
E0229 07:52:28.536252 1977 scheduler.cpp:575] End-Of-File received from master. The master closed the event stream
I0229 07:52:28.536525 1970 slave.cpp:3528] master@172.17.0.2:36666 exited
W0229 07:52:28.536561 1970 slave.cpp:3531] Master disconnected! Waiting for a new master to be elected
I0229 07:52:28.538324 1978 scheduler.cpp:414] Re-detecting master
I0229 07:52:28.538730 1978 scheduler.cpp:365] Ignoring disconnection attempt from stale connection
I0229 07:52:28.538800 1978 scheduler.cpp:365] Ignoring disconnection attempt from stale connection
I0229 07:52:28.538913 1978 scheduler.cpp:432] New master detected at master@172.17.0.2:36666
I0229 07:52:28.547485 1980 slave.cpp:3886] Executor 'default' of framework e8206980-7279-4f85-8625-302d893eeda3-0000 exited with status 0
I0229 07:52:28.547884 1980 slave.cpp:3002] Handling status update TASK_FAILED (UUID: e59313a3-837d-48c4-90cd-8d4969fc388c) for task 1 of framework e8206980-7279-4f85-8625-302d893eeda3-0000 from @0.0.0.0:0
I0229 07:52:28.548485 1980 slave.cpp:668] Slave terminating
I0229 07:52:28.548609 1980 slave.cpp:2079] Asked to shut down framework e8206980-7279-4f85-8625-302d893eeda3-0000 by @0.0.0.0:0
I0229 07:52:28.548660 1980 slave.cpp:2104] Shutting down framework e8206980-7279-4f85-8625-302d893eeda3-0000
I0229 07:52:28.548831 1980 slave.cpp:3990] Cleaning up executor 'default' of framework e8206980-7279-4f85-8625-302d893eeda3-0000 (via HTTP)
I0229 07:52:28.549221 1974 gc.cpp:54] Scheduling '/tmp/ContentType_SchedulerTest_TaskRunning_1_qU7Znf/slaves/e8206980-7279-4f85-8625-302d893eeda3-S0/frameworks/e8206980-7279-4f85-8625-302d893eeda3-0000/executors/default/runs/29fd6e7a-d4a1-4e45-b51d-045b87e1b962' for gc 6.99999364468741days in the future
I0229 07:52:28.549371 1980 slave.cpp:4078] Cleaning up framework e8206980-7279-4f85-8625-302d893eeda3-0000
I0229 07:52:28.549435 1974 gc.cpp:54] Scheduling '/tmp/ContentType_SchedulerTest_TaskRunning_1_qU7Znf/slaves/e8206980-7279-4f85-8625-302d893eeda3-S0/frameworks/e8206980-7279-4f85-8625-302d893eeda3-0000/executors/default' for gc 6.99999364282667days in the future
I0229 07:52:28.549654 1977 status_update_manager.cpp:282] Closing status update streams for framework e8206980-7279-4f85-8625-302d893eeda3-0000
I0229 07:52:28.550143 1977 status_update_manager.cpp:528] Cleaning up status update stream for task 1 of framework e8206980-7279-4f85-8625-302d893eeda3-0000
I0229 07:52:28.550509 1974 gc.cpp:54] Scheduling '/tmp/ContentType_SchedulerTest_TaskRunning_1_qU7Znf/slaves/e8206980-7279-4f85-8625-302d893eeda3-S0/frameworks/e8206980-7279-4f85-8625-302d893eeda3-0000' for gc 6.99999363873778days in the future
I0229 07:52:28.551605 1971 scheduler.cpp:332] Connected with the master at http://172.17.0.2:36666/master/api/v1/scheduler
F0229 07:52:28.551605 1971 logging.cpp:64] RAW: Pure virtual method called
[ OK ] ContentType/SchedulerTest.TaskRunning/1 (128 ms)
[ RUN ] ContentType/SchedulerTest.ReconcileTask/0
I0229 07:52:28.560551 1948 leveldb.cpp:174] Opened db in 2.91702ms
@ 0x7fb27b2032ee google::LogMessage::Fail()
I0229 07:52:28.563210 1948 leveldb.cpp:181] Compacted db in 2.444347ms
I0229 07:52:28.563403 1948 leveldb.cpp:196] Created db iterator in 24843ns
I0229 07:52:28.563586 1948 leveldb.cpp:202] Seeked to beginning of db in 2439ns
I0229 07:52:28.563760 1948 leveldb.cpp:271] Iterated through 0 keys in the db in 459ns
I0229 07:52:28.563973 1948 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0229 07:52:28.564810 1981 recover.cpp:447] Starting replica recovery
I0229 07:52:28.565296 1981 recover.cpp:473] Replica is in EMPTY status
@ 0x7fb27b2087d0 google::RawLog__()
I0229 07:52:28.567409 1970 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (14524)@172.17.0.2:36666
I0229 07:52:28.569335 1982 master.cpp:375] Master 221c2f02-5c17-41f5-b285-279d5abf296d (09af0089f551) started on 172.17.0.2:36666
I0229 07:52:28.569380 1977 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0229 07:52:28.569362 1982 master.cpp:377] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/2c8X11/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.28.0/_inst/share/mesos/webui" --work_dir="/tmp/2c8X11/master" --zk_session_timeout="10secs"
I0229 07:52:28.569747 1982 master.cpp:424] Master allowing unauthenticated frameworks to register
I0229 07:52:28.569764 1982 master.cpp:427] Master only allowing authenticated slaves to register
I0229 07:52:28.569777 1982 credentials.hpp:35] Loading credentials for authentication from '/tmp/2c8X11/credentials'
I0229 07:52:28.569954 1976 recover.cpp:564] Updating replica status to STARTING
I0229 07:52:28.570252 1982 master.cpp:467] Using default 'crammd5' authenticator
I0229 07:52:28.570423 1982 master.cpp:536] Using default 'basic' HTTP authenticator
I0229 07:52:28.570583 1982 master.cpp:570] Authorization enabled
I0229 07:52:28.570739 1973 whitelist_watcher.cpp:77] No whitelist given
I0229 07:52:28.570744 1977 hierarchical.cpp:144] Initialized hierarchical allocator process
I0229 07:52:28.570865 1978 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 600863ns
I0229 07:52:28.570888 1978 replica.cpp:320] Persisted replica status to STARTING
I0229 07:52:28.571100 1967 recover.cpp:473] Replica is in STARTING status
I0229 07:52:28.572049 1975 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (14526)@172.17.0.2:36666
I0229 07:52:28.572870 1973 master.cpp:1711] The newly elected leader is master@172.17.0.2:36666 with id 221c2f02-5c17-41f5-b285-279d5abf296d
I0229 07:52:28.572926 1973 master.cpp:1724] Elected as the leading master!
I0229 07:52:28.572949 1973 master.cpp:1469] Recovering from registrar
I0229 07:52:28.572940 1981 recover.cpp:193] Received a recover response from a replica in STARTING status
I0229 07:52:28.573178 1975 registrar.cpp:307] Recovering registrar
I0229 07:52:28.573917 1968 recover.cpp:564] Updating replica status to VOTING
@ 0x7fb27a377110 __cxa_pure_virtual
I0229 07:52:28.574609 1976 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 400838ns
I0229 07:52:28.574635 1976 replica.cpp:320] Persisted replica status to VOTING
I0229 07:52:28.574755 1980 recover.cpp:578] Successfully joined the Paxos group
I0229 07:52:28.574985 1980 recover.cpp:462] Recover process terminated
I0229 07:52:28.575448 1979 log.cpp:659] Attempting to start the writer
I0229 07:52:28.576624 1977 replica.cpp:493] Replica received implicit promise request from (14527)@172.17.0.2:36666 with proposal 1
I0229 07:52:28.577040 1977 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 382772ns
I0229 07:52:28.577061 1977 replica.cpp:342] Persisted promised to 1
I0229 07:52:28.577702 1981 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0229 07:52:28.578742 1976 replica.cpp:388] Replica received explicit promise request from (14528)@172.17.0.2:36666 for position 0 with proposal 2
I0229 07:52:28.579149 1976 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 369448ns
I0229 07:52:28.579181 1976 replica.cpp:712] Persisted action at 0
I0229 07:52:28.580065 1972 replica.cpp:537] Replica received write request for position 0 from (14529)@172.17.0.2:36666
I0229 07:52:28.580117 1972 leveldb.cpp:436] Reading position from leveldb took 26469ns
I0229 07:52:28.580456 1972 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 303476ns
I0229 07:52:28.580476 1972 replica.cpp:712] Persisted action at 0
I0229 07:52:28.581033 1980 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
@ 0x16fad07 testing::internal::UntypedFunctionMockerBase::UntypedInvokeWith()
I0229 07:52:28.581256 1980 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 162160ns
I0229 07:52:28.581282 1980 replica.cpp:712] Persisted action at 0
I0229 07:52:28.581300 1980 replica.cpp:697] Replica learned NOP action at position 0
I0229 07:52:28.581943 1968 log.cpp:675] Writer started with ending position 0
I0229 07:52:28.582834 1980 leveldb.cpp:436] Reading position from leveldb took 22024ns
I0229 07:52:28.583686 1977 registrar.cpp:340] Successfully fetched the registry (0B) in 10.423808ms
I0229 07:52:28.583820 1977 registrar.cpp:439] Applied 1 operations in 36615ns; attempting to update the 'registry'
I0229 07:52:28.584715 1975 log.cpp:683] Attempting to append 170 bytes to the log
I0229 07:52:28.584880 1967 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0229 07:52:28.585803 1980 replica.cpp:537] Replica received write request for position 1 from (14530)@172.17.0.2:36666
I0229 07:52:28.586460 1980 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 609870ns
I0229 07:52:28.586493 1980 replica.cpp:712] Persisted action at 1
I0229 07:52:28.587194 1973 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0229 07:52:28.587641 1973 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 410438ns
I0229 07:52:28.587678 1973 replica.cpp:712] Persisted action at 1
I0229 07:52:28.587714 1973 replica.cpp:697] Replica learned APPEND action at position 1
I0229 07:52:28.588883 1979 registrar.cpp:484] Successfully updated the 'registry' in 4.990976ms
I0229 07:52:28.589066 1979 registrar.cpp:370] Successfully recovered registrar
I0229 07:52:28.589224 1978 log.cpp:702] Attempting to truncate the log to 1
I0229 07:52:28.589478 1972 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
@ 0xf32c81 testing::internal::FunctionMockerBase<>::InvokeWith()
I0229 07:52:28.589879 1973 master.cpp:1521] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0229 07:52:28.590001 1969 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0229 07:52:28.590641 1968 replica.cpp:537] Replica received write request for position 2 from (14531)@172.17.0.2:36666
@ 0xf2ed83 testing::internal::FunctionMocker<>::Invoke()
I0229 07:52:28.591279 1968 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 594134ns
I0229 07:52:28.591301 1968 replica.cpp:712] Persisted action at 2
I0229 07:52:28.591924 1974 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0229 07:52:28.592562 1974 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 464078ns
I0229 07:52:28.592639 1974 leveldb.cpp:399] Deleting ~1 keys from leveldb took 50191ns
I0229 07:52:28.592664 1974 replica.cpp:712] Persisted action at 2
I0229 07:52:28.592692 1974 replica.cpp:697] Replica learned TRUNCATE action at position 2
@ 0x132e4f0 mesos::internal::tests::SchedulerTest::Callbacks::connected()
@ 0x1342c87 std::_Mem_fn<>::operator()<>()
@ 0x133fb4a _ZNSt5_BindIFSt7_Mem_fnIMN5mesos8internal5tests13SchedulerTest9CallbacksEFvvEESt17reference_wrapperIS5_EEE6__callIvJEJLm0EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
@ 0x133b406 std::_Bind<>::operator()<>()
@ 0x1334ef5 std::_Function_handler<>::_M_invoke()
@ 0x9a3e72 std::function<>::operator()()
@ 0x7fb27a2ccc59 process::AsyncExecutorProcess::execute<>()
@ 0x7fb27a2d43b9 _ZZN7process8dispatchI7NothingNS_20AsyncExecutorProcessERKSt8functionIFvvEEPvS5_S8_EENS_6FutureIT_EERKNS_3PIDIT0_EEMSD_FSA_T1_T2_ET3_T4_ENKUlPNS_11ProcessBaseEE_clESO_
@ 0x7fb27a2e00ed _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchI7NothingNS0_20AsyncExecutorProcessERKSt8functionIFvvEEPvS9_SC_EENS0_6FutureIT_EERKNS0_3PIDIT0_EEMSH_FSE_T1_T2_ET3_T4_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_
@ 0x7fb27b1820d1 std::function<>::operator()()
@ 0x7fb27b167b79 process::ProcessBase::visit()
@ 0x7fb27b16d9e0 process::DispatchEvent::visit()
@ 0x9a2460 process::ProcessBase::serve()
@ 0x7fb27b163e99 process::ProcessManager::resume()
@ 0x7fb27b160fa6 _ZZN7process14ProcessManager12init_threadsEvENKUlRKSt11atomic_boolE_clES3_
@ 0x7fb27b16d1cc _ZNSt5_BindIFZN7process14ProcessManager12init_threadsEvEUlRKSt11atomic_boolE_St17reference_wrapperIS3_EEE6__callIvIEILm0EEEET_OSt5tupleIIDpT0_EESt12_Index_tupleIIXspT1_EEE
@ 0x7fb27b16d17c _ZNSt5_BindIFZN7process14ProcessManager12init_threadsEvEUlRKSt11atomic_boolE_St17reference_wrapperIS3_EEEclIIEvEET0_DpOT_
@ 0x7fb27b16d10e _ZNSt12_Bind_simpleIFSt5_BindIFZN7process14ProcessManager12init_threadsEvEUlRKSt11atomic_boolE_St17reference_wrapperIS4_EEEvEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x7fb27b16d065 _ZNSt12_Bind_simpleIFSt5_BindIFZN7process14ProcessManager12init_threadsEvEUlRKSt11atomic_boolE_St17reference_wrapperIS4_EEEvEEclEv
@ 0x7fb27b16cffe _ZNSt6thread5_ImplISt12_Bind_simpleIFSt5_BindIFZN7process14ProcessManager12init_threadsEvEUlRKSt11atomic_boolE_St17reference_wrapperIS6_EEEvEEE6_M_runEv
@ 0x7fb27624c220 (unknown)
@ 0x7fb2768b2dc5 start_thread
@ 0x7fb2759b428d __clone
make[4]: *** [check-local] Aborted
make[4]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.28.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1456730783-5043
Untagged: mesos-1456730783-5043:latest
Deleted: d76d5621899f815c87539215ef5b38fee0cbe34519f82e1134cffa0d23277625
Deleted: f83e6b9b93a0fa9ad61b16ac76a51350ca93f22a55c565db0baf6cc6fb8cffc0
Deleted: 8b627a761a2dcd5b0767d8dcd6d2df95cf3b0560b9616bd58064ad8873ddc31a
Deleted: d6eb4bcf11a8efcb7d0382931d1eb680282f2c6c431afe68344fe9c2072b8473
Deleted: 0b40e05be92376478efa23e979124f1192ebdfb28900acfbb07adb5ecc14aba9
Deleted: 1d31a3cc07d319415698867a2abb563a109d8f636020ea0bcc2667557264d05c
Deleted: 6e1fa0c1a44dddbedfd08dd105f8bccd1da012caf1d975421322b3d24baadf93
Deleted: a1c01a69a9ab645d4c36deafdaecc80505e7859df299d3e9751bfdca9e2bdc16
Deleted: 71ce65589e066827009997c5a97cbdbc595adc9c221c3b8d7780dd3a6010a0c3
Deleted: a1de0214dc7cec9d02deb9b9c712612c5a0774ecd425c2e7370692f17fc69eb3
Deleted: 70e349bfe0d3fa3e58dd5b401a97de5e95d4cf2343d16560d94bbbb4e6dd498c
Deleted: 7fc0c5d410b49a408b24f6af9c6de4d5204e075dc2fc50cf4ed76290f2848bbc
Deleted: 9e3c0f1dae20304358f3d8f8d3690a6d6d4f855d58333c782c689eaf6ba35cef
Deleted: eef82dde15459e1f155cee82154b7e36786f367bea4296c82f78018dc15db118
Deleted: f970559407827c13687ba669caf8ee80176451cb75bb72500544137eacedca53
Deleted: e37986339f80d73d2bea4b441c8a15705662d68641aa6d17c89aedf04faba322
Deleted: 2933d50b9f770c1520d5975c7c231ba1c47ac513930fd396d14ff300921b14d0
Deleted: 5d358abc5d9c5929b766ca8be724976662808cbdc066ad386bb13197b265f6ea
Deleted: b4af4261cb1508e2c73a5722445d5f3837f1c59ca3278ab80eb9a31739cb1a0e
Deleted: fa5be2806d4c9aa0f75001687087876e47bb45dc8afb61f0c0e46315500ee144
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos » gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1) #1746
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1746/changes>