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/03/29 08:32:33 UTC

Build failed in Jenkins: Mesos » clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1888

See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1888/changes>

Changes:

[yujie.jay] Adapted port_mapping isolator with missing subprocess parameter.

[yujie.jay] Fixed typo in subprocess doxygen comments.

------------------------------------------
[...truncated 178901 lines...]
I0329 06:24:26.085312 32305 master.cpp:378] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/h70a8A/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.29.0/_inst/share/mesos/webui" --work_dir="/tmp/h70a8A/master" --zk_session_timeout="10secs"
I0329 06:24:26.085846 32305 master.cpp:429] Master allowing unauthenticated frameworks to register
I0329 06:24:26.085932 32305 master.cpp:432] Master only allowing authenticated slaves to register
I0329 06:24:26.086014 32305 credentials.hpp:37] Loading credentials for authentication from '/tmp/h70a8A/credentials'
I0329 06:24:26.089220 32305 master.cpp:474] Using default 'crammd5' authenticator
I0329 06:24:26.089391 32305 master.cpp:545] Using default 'basic' HTTP authenticator
I0329 06:24:26.089530 32305 master.cpp:583] Authorization enabled
I0329 06:24:26.092001 32305 hierarchical.cpp:144] Initialized hierarchical allocator process
I0329 06:24:26.092093 32305 whitelist_watcher.cpp:77] No whitelist given
I0329 06:24:26.093214 32310 master.cpp:1826] The newly elected leader is master@172.17.0.2:44577 with id c0df3402-8c18-4794-aea1-d0fc2093455d
I0329 06:24:26.093361 32310 master.cpp:1839] Elected as the leading master!
I0329 06:24:26.093479 32310 master.cpp:1526] Recovering from registrar
I0329 06:24:26.093793 32302 registrar.cpp:307] Recovering registrar
I0329 06:24:26.097321 32299 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 28.543546ms
I0329 06:24:26.097383 32299 replica.cpp:320] Persisted replica status to STARTING
I0329 06:24:26.097652 32299 recover.cpp:473] Replica is in STARTING status
I0329 06:24:26.099536 32300 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (16412)@172.17.0.2:44577
I0329 06:24:26.100143 32300 recover.cpp:193] Received a recover response from a replica in STARTING status
I0329 06:24:26.100862 32300 recover.cpp:564] Updating replica status to VOTING
I0329 06:24:26.131098 32300 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.047383ms
I0329 06:24:26.131202 32300 replica.cpp:320] Persisted replica status to VOTING
I0329 06:24:26.131471 32300 recover.cpp:578] Successfully joined the Paxos group
I0329 06:24:26.131674 32300 recover.cpp:462] Recover process terminated
I0329 06:24:26.132522 32300 log.cpp:659] Attempting to start the writer
I0329 06:24:26.134305 32300 replica.cpp:493] Replica received implicit promise request from (16413)@172.17.0.2:44577 with proposal 1
I0329 06:24:26.164098 32300 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 29.777838ms
I0329 06:24:26.164202 32300 replica.cpp:342] Persisted promised to 1
I0329 06:24:26.174093 32307 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0329 06:24:26.177204 32307 replica.cpp:388] Replica received explicit promise request from (16414)@172.17.0.2:44577 for position 0 with proposal 2
I0329 06:24:26.202256 32307 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 24.828611ms
I0329 06:24:26.202365 32307 replica.cpp:712] Persisted action at 0
I0329 06:24:26.204294 32307 replica.cpp:537] Replica received write request for position 0 from (16415)@172.17.0.2:44577
I0329 06:24:26.204396 32307 leveldb.cpp:436] Reading position from leveldb took 63731ns
I0329 06:24:26.227409 32307 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 22.997586ms
I0329 06:24:26.227517 32307 replica.cpp:712] Persisted action at 0
I0329 06:24:26.233665 32299 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0329 06:24:26.252598 32299 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 18.804081ms
I0329 06:24:26.252712 32299 replica.cpp:712] Persisted action at 0
I0329 06:24:26.252753 32299 replica.cpp:697] Replica learned NOP action at position 0
I0329 06:24:26.254039 32299 log.cpp:675] Writer started with ending position 0
I0329 06:24:26.255581 32299 leveldb.cpp:436] Reading position from leveldb took 100584ns
I0329 06:24:26.263558 32306 registrar.cpp:340] Successfully fetched the registry (0B) in 169.595904ms
I0329 06:24:26.263902 32306 registrar.cpp:439] Applied 1 operations in 60146ns; attempting to update the 'registry'
I0329 06:24:26.266656 32304 log.cpp:683] Attempting to append 170 bytes to the log
I0329 06:24:26.266984 32304 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0329 06:24:26.268291 32304 replica.cpp:537] Replica received write request for position 1 from (16416)@172.17.0.2:44577
I0329 06:24:26.294421 32304 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 26.109178ms
I0329 06:24:26.294524 32304 replica.cpp:712] Persisted action at 1
I0329 06:24:26.296066 32304 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0329 06:24:26.319604 32304 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 23.539377ms
I0329 06:24:26.319721 32304 replica.cpp:712] Persisted action at 1
I0329 06:24:26.319761 32304 replica.cpp:697] Replica learned APPEND action at position 1
I0329 06:24:26.322026 32304 registrar.cpp:484] Successfully updated the 'registry' in 58.029056ms
I0329 06:24:26.322201 32304 registrar.cpp:370] Successfully recovered registrar
I0329 06:24:26.322477 32304 log.cpp:702] Attempting to truncate the log to 1
I0329 06:24:26.323202 32309 master.cpp:1634] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0329 06:24:26.323429 32309 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0329 06:24:26.323717 32296 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0329 06:24:26.325094 32296 replica.cpp:537] Replica received write request for position 2 from (16417)@172.17.0.2:44577
I0329 06:24:26.344977 32296 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 19.869767ms
I0329 06:24:26.345098 32296 replica.cpp:712] Persisted action at 2
I0329 06:24:26.346740 32296 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0329 06:24:26.370178 32296 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 23.433093ms
I0329 06:24:26.370374 32296 leveldb.cpp:399] Deleting ~1 keys from leveldb took 101580ns
I0329 06:24:26.370405 32296 replica.cpp:712] Persisted action at 2
I0329 06:24:26.370445 32296 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0329 06:24:26.375056 32278 scheduler.cpp:172] Version: 0.29.0
I0329 06:24:26.376142 32296 scheduler.cpp:438] New master detected at master@172.17.0.2:44577
I0329 06:24:26.378262 32304 scheduler.cpp:338] Connected with the master at http://172.17.0.2:44577/master/api/v1/scheduler
I0329 06:24:26.379989 32298 scheduler.cpp:231] Sending SUBSCRIBE call to http://172.17.0.2:44577/master/api/v1/scheduler
I0329 06:24:26.381155 32304 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0329 06:24:26.381928 32297 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:60727
I0329 06:24:26.382292 32297 master.cpp:2088] Received subscription request for HTTP framework 'default'
I0329 06:24:26.382323 32297 master.cpp:1865] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0329 06:24:26.383196 32297 master.cpp:2179] Subscribing framework 'default' with checkpointing disabled and capabilities [  ]
I0329 06:24:26.384454 32297 hierarchical.cpp:266] Added framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000
I0329 06:24:26.384557 32297 hierarchical.cpp:1490] No resources available to allocate!
I0329 06:24:26.384598 32297 hierarchical.cpp:1585] No inverse offers to send out!
I0329 06:24:26.384667 32297 hierarchical.cpp:1141] Performed allocation for 0 slaves in 195737ns
I0329 06:24:26.384935 32297 master.hpp:1715] Sending heartbeat to c0df3402-8c18-4794-aea1-d0fc2093455d-0000
I0329 06:24:26.385965 32306 scheduler.cpp:629] Enqueuing event SUBSCRIBED received from http://172.17.0.2:44577/master/api/v1/scheduler
I0329 06:24:26.386927 32306 scheduler.cpp:629] Enqueuing event HEARTBEAT received from http://172.17.0.2:44577/master/api/v1/scheduler
I0329 06:24:26.388162 32301 scheduler.cpp:231] Sending REQUEST call to http://172.17.0.2:44577/master/api/v1/scheduler
I0329 06:24:26.389529 32301 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0329 06:24:26.390229 32300 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:60728
I0329 06:24:26.390472 32300 master.cpp:2785] Processing REQUEST call for framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
I0329 06:24:26.390704 32303 hierarchical.cpp:592] Received resource request from framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000
I0329 06:24:26.394057 32305 master.cpp:1269] Framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default) disconnected
I0329 06:24:26.394111 32305 master.cpp:2642] Disconnecting framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
I0329 06:24:26.394139 32305 master.cpp:2666] Deactivating framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
I0329 06:24:26.394261 32305 master.cpp:1293] Giving framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default) 0ns to failover
I0329 06:24:26.394523 32305 hierarchical.cpp:378] Deactivated framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000
I0329 06:24:26.395911 32305 master.cpp:5360] Framework failover timeout, removing framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
I0329 06:24:26.395953 32305 master.cpp:6093] Removing framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
I0329 06:24:26.396348 32305 hierarchical.cpp:329] Removed framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000
I0329 06:24:26.404429 32278 master.cpp:1083] Master terminating
[       OK ] ContentType/SchedulerTest.Request/0 (666 ms)
[ RUN      ] ContentType/SchedulerTest.Request/1
I0329 06:24:26.430703 32278 cluster.cpp:139] Creating default 'local' authorizer
I0329 06:24:26.515738 32278 leveldb.cpp:174] Opened db in 84.58043ms
I0329 06:24:26.541720 32278 leveldb.cpp:181] Compacted db in 25.941366ms
I0329 06:24:26.541827 32278 leveldb.cpp:196] Created db iterator in 35061ns
I0329 06:24:26.541844 32278 leveldb.cpp:202] Seeked to beginning of db in 9132ns
I0329 06:24:26.541856 32278 leveldb.cpp:271] Iterated through 0 keys in the db in 6113ns
I0329 06:24:26.541901 32278 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0329 06:24:26.542896 32309 recover.cpp:447] Starting replica recovery
I0329 06:24:26.543231 32309 recover.cpp:473] Replica is in EMPTY status
I0329 06:24:26.544759 32302 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (16425)@172.17.0.2:44577
I0329 06:24:26.545496 32309 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0329 06:24:26.546010 32309 recover.cpp:564] Updating replica status to STARTING
I0329 06:24:26.550267 32300 master.cpp:376] Master e055dc0c-92ec-4a53-9dad-b00b45fc8a0c (58e403dcc250) started on 172.17.0.2:44577
I0329 06:24:26.550334 32300 master.cpp:378] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/rgiyDU/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.29.0/_inst/share/mesos/webui" --work_dir="/tmp/rgiyDU/master" --zk_session_timeout="10secs"
I0329 06:24:26.550730 32300 master.cpp:429] Master allowing unauthenticated frameworks to register
I0329 06:24:26.550742 32300 master.cpp:432] Master only allowing authenticated slaves to register
I0329 06:24:26.550750 32300 credentials.hpp:37] Loading credentials for authentication from '/tmp/rgiyDU/credentials'
I0329 06:24:26.551120 32300 master.cpp:474] Using default 'crammd5' authenticator
I0329 06:24:26.551282 32300 master.cpp:545] Using default 'basic' HTTP authenticator
I0329 06:24:26.551408 32300 master.cpp:583] Authorization enabled
I0329 06:24:26.553966 32301 whitelist_watcher.cpp:77] No whitelist given
I0329 06:24:26.554941 32308 master.cpp:1826] The newly elected leader is master@172.17.0.2:44577 with id e055dc0c-92ec-4a53-9dad-b00b45fc8a0c
I0329 06:24:26.555119 32308 master.cpp:1839] Elected as the leading master!
I0329 06:24:26.555203 32308 master.cpp:1526] Recovering from registrar
I0329 06:24:26.555528 32308 registrar.cpp:307] Recovering registrar
I0329 06:24:26.556252 32300 hierarchical.cpp:144] Initialized hierarchical allocator process
I0329 06:24:26.583319 32309 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 37.070461ms
I0329 06:24:26.583428 32309 replica.cpp:320] Persisted replica status to STARTING
I0329 06:24:26.583833 32300 recover.cpp:473] Replica is in STARTING status
I0329 06:24:26.585232 32307 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (16427)@172.17.0.2:44577
I0329 06:24:26.585587 32299 recover.cpp:193] Received a recover response from a replica in STARTING status
I0329 06:24:26.586372 32307 recover.cpp:564] Updating replica status to VOTING
I0329 06:24:26.625116 32298 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 38.529877ms
I0329 06:24:26.625231 32298 replica.cpp:320] Persisted replica status to VOTING
I0329 06:24:26.625517 32303 recover.cpp:578] Successfully joined the Paxos group
I0329 06:24:26.625844 32303 recover.cpp:462] Recover process terminated
I0329 06:24:26.626432 32311 log.cpp:659] Attempting to start the writer
I0329 06:24:26.627914 32309 replica.cpp:493] Replica received implicit promise request from (16428)@172.17.0.2:44577 with proposal 1
I0329 06:24:26.658563 32309 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.632068ms
I0329 06:24:26.658671 32309 replica.cpp:342] Persisted promised to 1
I0329 06:24:26.659965 32299 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0329 06:24:26.661671 32307 replica.cpp:388] Replica received explicit promise request from (16429)@172.17.0.2:44577 for position 0 with proposal 2
I0329 06:24:26.700374 32307 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 38.66346ms
I0329 06:24:26.700486 32307 replica.cpp:712] Persisted action at 0
I0329 06:24:26.702354 32301 replica.cpp:537] Replica received write request for position 0 from (16430)@172.17.0.2:44577
I0329 06:24:26.702471 32301 leveldb.cpp:436] Reading position from leveldb took 60629ns
I0329 06:24:26.733788 32301 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 31.29248ms
I0329 06:24:26.733875 32301 replica.cpp:712] Persisted action at 0
I0329 06:24:26.734787 32307 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0329 06:24:26.766201 32307 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 31.401125ms
I0329 06:24:26.766286 32307 replica.cpp:712] Persisted action at 0
I0329 06:24:26.766320 32307 replica.cpp:697] Replica learned NOP action at position 0
I0329 06:24:26.767251 32301 log.cpp:675] Writer started with ending position 0
I0329 06:24:26.768996 32298 leveldb.cpp:436] Reading position from leveldb took 111416ns
I0329 06:24:26.770465 32298 registrar.cpp:340] Successfully fetched the registry (0B) in 214.683136ms
I0329 06:24:26.770622 32298 registrar.cpp:439] Applied 1 operations in 54730ns; attempting to update the 'registry'
I0329 06:24:26.772197 32298 log.cpp:683] Attempting to append 170 bytes to the log
I0329 06:24:26.772478 32299 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0329 06:24:26.773361 32302 replica.cpp:537] Replica received write request for position 1 from (16431)@172.17.0.2:44577
I0329 06:24:26.812733 32302 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 39.346476ms
I0329 06:24:26.812857 32302 replica.cpp:712] Persisted action at 1
I0329 06:24:26.813958 32299 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0329 06:24:26.837826 32299 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 23.866792ms
I0329 06:24:26.837930 32299 replica.cpp:712] Persisted action at 1
I0329 06:24:26.837967 32299 replica.cpp:697] Replica learned APPEND action at position 1
I0329 06:24:26.839619 32311 registrar.cpp:484] Successfully updated the 'registry' in 68.916992ms
I0329 06:24:26.839851 32311 registrar.cpp:370] Successfully recovered registrar
I0329 06:24:26.839921 32300 log.cpp:702] Attempting to truncate the log to 1
I0329 06:24:26.840009 32299 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0329 06:24:26.840361 32300 master.cpp:1634] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0329 06:24:26.840445 32305 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0329 06:24:26.841119 32306 replica.cpp:537] Replica received write request for position 2 from (16432)@172.17.0.2:44577
I0329 06:24:26.863555 32306 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 22.418343ms
I0329 06:24:26.863641 32306 replica.cpp:712] Persisted action at 2
I0329 06:24:26.865054 32306 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0329 06:24:26.883360 32306 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 18.305162ms
I0329 06:24:26.883512 32306 leveldb.cpp:399] Deleting ~1 keys from leveldb took 83579ns
I0329 06:24:26.883538 32306 replica.cpp:712] Persisted action at 2
I0329 06:24:26.883570 32306 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0329 06:24:26.891973 32278 scheduler.cpp:172] Version: 0.29.0
I0329 06:24:26.892935 32302 scheduler.cpp:438] New master detected at master@172.17.0.2:44577
I0329 06:24:26.894947 32298 scheduler.cpp:338] Connected with the master at http://172.17.0.2:44577/master/api/v1/scheduler
I0329 06:24:26.896307 32309 scheduler.cpp:231] Sending SUBSCRIBE call to http://172.17.0.2:44577/master/api/v1/scheduler
I0329 06:24:26.897629 32309 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0329 06:24:26.898205 32309 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:60732
I0329 06:24:26.898597 32309 master.cpp:2088] Received subscription request for HTTP framework 'default'
I0329 06:24:26.898622 32309 master.cpp:1865] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0329 06:24:26.899363 32309 master.cpp:2179] Subscribing framework 'default' with checkpointing disabled and capabilities [  ]
I0329 06:24:26.900554 32309 hierarchical.cpp:266] Added framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
I0329 06:24:26.900652 32309 hierarchical.cpp:1490] No resources available to allocate!
I0329 06:24:26.900890 32303 master.hpp:1715] Sending heartbeat to e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
I0329 06:24:26.902096 32304 scheduler.cpp:629] Enqueuing event SUBSCRIBED received from http://172.17.0.2:44577/master/api/v1/scheduler
I0329 06:24:26.902498 32304 scheduler.cpp:629] Enqueuing event HEARTBEAT received from http://172.17.0.2:44577/master/api/v1/scheduler
I0329 06:24:26.902828 32309 hierarchical.cpp:1585] No inverse offers to send out!
I0329 06:24:26.902901 32309 hierarchical.cpp:1141] Performed allocation for 0 slaves in 2.330913ms
I0329 06:24:26.903455 32308 scheduler.cpp:231] Sending REQUEST call to http://172.17.0.2:44577/master/api/v1/scheduler
I0329 06:24:26.904709 32302 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0329 06:24:26.905304 32302 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:60733
I0329 06:24:26.905647 32302 master.cpp:2785] Processing REQUEST call for framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
I0329 06:24:26.905892 32302 hierarchical.cpp:592] Received resource request from framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
I0329 06:24:26.908712 32304 master.cpp:1269] Framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default) disconnected
I0329 06:24:26.908759 32304 master.cpp:2642] Disconnecting framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
I0329 06:24:26.908783 32304 master.cpp:2666] Deactivating framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
I0329 06:24:26.908882 32304 master.cpp:1293] Giving framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default) 0ns to failover
I0329 06:24:26.909106 32304 hierarchical.cpp:378] Deactivated framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
I0329 06:24:26.910423 32304 master.cpp:5360] Framework failover timeout, removing framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
I0329 06:24:26.910450 32304 master.cpp:6093] Removing framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
I0329 06:24:26.910758 32304 hierarchical.cpp:329] Removed framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
I0329 06:24:26.919173 32278 master.cpp:1083] Master terminating
[       OK ] ContentType/SchedulerTest.Request/1 (499 ms)
[----------] 26 tests from ContentType/SchedulerTest (19532 ms total)

[----------] Global test environment tear-down
E0329 06:24:27.207061 32278 environment.cpp:778] Failed to remove '/tmp/LogZooKeeperTest_WriteRead_isx6ON': No such file or directory
E0329 06:24:27.207288 32278 environment.cpp:778] Failed to remove '/tmp/LogZooKeeperTest_LostZooKeeper_UHzb62': No such file or directory
[==========] 1052 tests from 132 test cases ran. (788517 ms total)
[  PASSED  ] 1051 tests.
[  FAILED  ] 1 test, listed below:
[  FAILED  ] ContainerLoggerTest.LOGROTATE_RotateInSandbox

 1 FAILED TEST
  YOU HAVE 6 DISABLED TESTS

make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[1]: Leaving directory `/mesos/mesos-0.29.0/_build'
make[1]: *** [check-recursive] Error 1
make: *** [distcheck] Error 1
+ docker rmi mesos-1459230127-13906
Untagged: mesos-1459230127-13906:latest
Deleted: cacb8f6afe4d7fdbe1ac7bf1ad0338181413f3a81b5b1c5cf56bf2667f810aad
Deleted: f48e36119876751f898378fa38c0e9cc8f01f3566284450b1e917b855f4520c5
Deleted: 90526e7a88d474bb45f1edb44c7cc3eea0151ecca4be9ec0d3765d1888fa8315
Deleted: 76e7df84fa713c1a9786f5f1f4c4dda9be08c32e292c151e01049bb1cbcd448c
Deleted: cac845a110e827f30f8bcde5084d1a8b2b252fd7e3593d123763a75d1d9f7af8
Deleted: 2304e9792ef33b93a77f035160b9de70501c135bddfe18a6f16023f51faeb60a
Deleted: 6eb7b0792e8661dd7e8d1d95eb8549bda73ce4da64e2274240ebf39d73ee0ffb
Deleted: cb90fa476408199deb8e11824723b16bc05ca545548b773aa30a49f2295f8f77
Deleted: f7d306edca1ab89b22abc8e15bd4bac995e67183c4620cf654c15721598f9c4d
Deleted: 76558ca548aa164ffe882d74281b3d03f6be8cc76b5b71b7b6cee7d1e95f9677
Deleted: a236590f9a5e81a39c2acf4e88ba0dc7706b44b6093ddb6a37d5809c6e27f3e7
Deleted: 6507f80835107a1b45ec2612528378e6ea33acc3ab14e635cd9bb4e6cf217073
Deleted: 8fbd0d2550c193ccb5107a9a13540cd1646e18d01286449d09cb9c7184d77186
Deleted: bc9b5531d8a17a788ca2cec0731fdc7ef3f3e7d3a0e6c8c10e5e20dbc38a60d5
Deleted: b005ae3936a4887eeee6a9ce4d5a41ba3e61b8ea4267ed01e09a3cbdca026684
Deleted: 80c1f8ce2529191ea73a5ab2339172c0c801f7e56bd326b240015eae5c680502
Build step 'Execute shell' marked build as failure

Re: Build failed in Jenkins: Mesos » clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1888

Posted by Benjamin Mahler <bm...@apache.org>.
Thanks for fixing!

On Tue, Mar 29, 2016 at 11:36 AM, Joseph Wu <jo...@mesosphere.io> wrote:

> Tracked here: https://issues.apache.org/jira/browse/MESOS-4961
>
> On Tue, Mar 29, 2016 at 11:22 AM, Benjamin Mahler <bm...@apache.org>
> wrote:
>
> > +joseph
> >
> > On Mon, Mar 28, 2016 at 11:32 PM, Apache Jenkins Server <
> > jenkins@builds.apache.org> wrote:
> >
> >> See <
> >>
> https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1888/changes
> >> >
> >>
> >> Changes:
> >>
> >> [yujie.jay] Adapted port_mapping isolator with missing subprocess
> >> parameter.
> >>
> >> [yujie.jay] Fixed typo in subprocess doxygen comments.
> >>
> >> ------------------------------------------
> >> [...truncated 178901 lines...]
> >> I0329 06:24:26.085312 32305 master.cpp:378] Flags at startup: --acls=""
> >> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> >> --authenticate="false" --authenticate_http="true"
> >> --authenticate_slaves="true" --authenticators="crammd5"
> >> --authorizers="local" --credentials="/tmp/h70a8A/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.29.0/_inst/share/mesos/webui"
> >> --work_dir="/tmp/h70a8A/master" --zk_session_timeout="10secs"
> >> I0329 06:24:26.085846 32305 master.cpp:429] Master allowing
> >> unauthenticated frameworks to register
> >> I0329 06:24:26.085932 32305 master.cpp:432] Master only allowing
> >> authenticated slaves to register
> >> I0329 06:24:26.086014 32305 credentials.hpp:37] Loading credentials for
> >> authentication from '/tmp/h70a8A/credentials'
> >> I0329 06:24:26.089220 32305 master.cpp:474] Using default 'crammd5'
> >> authenticator
> >> I0329 06:24:26.089391 32305 master.cpp:545] Using default 'basic' HTTP
> >> authenticator
> >> I0329 06:24:26.089530 32305 master.cpp:583] Authorization enabled
> >> I0329 06:24:26.092001 32305 hierarchical.cpp:144] Initialized
> >> hierarchical allocator process
> >> I0329 06:24:26.092093 32305 whitelist_watcher.cpp:77] No whitelist given
> >> I0329 06:24:26.093214 32310 master.cpp:1826] The newly elected leader is
> >> master@172.17.0.2:44577 with id c0df3402-8c18-4794-aea1-d0fc2093455d
> >> I0329 06:24:26.093361 32310 master.cpp:1839] Elected as the leading
> >> master!
> >> I0329 06:24:26.093479 32310 master.cpp:1526] Recovering from registrar
> >> I0329 06:24:26.093793 32302 registrar.cpp:307] Recovering registrar
> >> I0329 06:24:26.097321 32299 leveldb.cpp:304] Persisting metadata (8
> >> bytes) to leveldb took 28.543546ms
> >> I0329 06:24:26.097383 32299 replica.cpp:320] Persisted replica status to
> >> STARTING
> >> I0329 06:24:26.097652 32299 recover.cpp:473] Replica is in STARTING
> status
> >> I0329 06:24:26.099536 32300 replica.cpp:673] Replica in STARTING status
> >> received a broadcasted recover request from (16412)@172.17.0.2:44577
> >> I0329 06:24:26.100143 32300 recover.cpp:193] Received a recover response
> >> from a replica in STARTING status
> >> I0329 06:24:26.100862 32300 recover.cpp:564] Updating replica status to
> >> VOTING
> >> I0329 06:24:26.131098 32300 leveldb.cpp:304] Persisting metadata (8
> >> bytes) to leveldb took 30.047383ms
> >> I0329 06:24:26.131202 32300 replica.cpp:320] Persisted replica status to
> >> VOTING
> >> I0329 06:24:26.131471 32300 recover.cpp:578] Successfully joined the
> >> Paxos group
> >> I0329 06:24:26.131674 32300 recover.cpp:462] Recover process terminated
> >> I0329 06:24:26.132522 32300 log.cpp:659] Attempting to start the writer
> >> I0329 06:24:26.134305 32300 replica.cpp:493] Replica received implicit
> >> promise request from (16413)@172.17.0.2:44577 with proposal 1
> >> I0329 06:24:26.164098 32300 leveldb.cpp:304] Persisting metadata (8
> >> bytes) to leveldb took 29.777838ms
> >> I0329 06:24:26.164202 32300 replica.cpp:342] Persisted promised to 1
> >> I0329 06:24:26.174093 32307 coordinator.cpp:238] Coordinator attempting
> >> to fill missing positions
> >> I0329 06:24:26.177204 32307 replica.cpp:388] Replica received explicit
> >> promise request from (16414)@172.17.0.2:44577 for position 0 with
> >> proposal 2
> >> I0329 06:24:26.202256 32307 leveldb.cpp:341] Persisting action (8 bytes)
> >> to leveldb took 24.828611ms
> >> I0329 06:24:26.202365 32307 replica.cpp:712] Persisted action at 0
> >> I0329 06:24:26.204294 32307 replica.cpp:537] Replica received write
> >> request for position 0 from (16415)@172.17.0.2:44577
> >> I0329 06:24:26.204396 32307 leveldb.cpp:436] Reading position from
> >> leveldb took 63731ns
> >> I0329 06:24:26.227409 32307 leveldb.cpp:341] Persisting action (14
> bytes)
> >> to leveldb took 22.997586ms
> >> I0329 06:24:26.227517 32307 replica.cpp:712] Persisted action at 0
> >> I0329 06:24:26.233665 32299 replica.cpp:691] Replica received learned
> >> notice for position 0 from @0.0.0.0:0
> >> I0329 06:24:26.252598 32299 leveldb.cpp:341] Persisting action (16
> bytes)
> >> to leveldb took 18.804081ms
> >> I0329 06:24:26.252712 32299 replica.cpp:712] Persisted action at 0
> >> I0329 06:24:26.252753 32299 replica.cpp:697] Replica learned NOP action
> >> at position 0
> >> I0329 06:24:26.254039 32299 log.cpp:675] Writer started with ending
> >> position 0
> >> I0329 06:24:26.255581 32299 leveldb.cpp:436] Reading position from
> >> leveldb took 100584ns
> >> I0329 06:24:26.263558 32306 registrar.cpp:340] Successfully fetched the
> >> registry (0B) in 169.595904ms
> >> I0329 06:24:26.263902 32306 registrar.cpp:439] Applied 1 operations in
> >> 60146ns; attempting to update the 'registry'
> >> I0329 06:24:26.266656 32304 log.cpp:683] Attempting to append 170 bytes
> >> to the log
> >> I0329 06:24:26.266984 32304 coordinator.cpp:348] Coordinator attempting
> >> to write APPEND action at position 1
> >> I0329 06:24:26.268291 32304 replica.cpp:537] Replica received write
> >> request for position 1 from (16416)@172.17.0.2:44577
> >> I0329 06:24:26.294421 32304 leveldb.cpp:341] Persisting action (189
> >> bytes) to leveldb took 26.109178ms
> >> I0329 06:24:26.294524 32304 replica.cpp:712] Persisted action at 1
> >> I0329 06:24:26.296066 32304 replica.cpp:691] Replica received learned
> >> notice for position 1 from @0.0.0.0:0
> >> I0329 06:24:26.319604 32304 leveldb.cpp:341] Persisting action (191
> >> bytes) to leveldb took 23.539377ms
> >> I0329 06:24:26.319721 32304 replica.cpp:712] Persisted action at 1
> >> I0329 06:24:26.319761 32304 replica.cpp:697] Replica learned APPEND
> >> action at position 1
> >> I0329 06:24:26.322026 32304 registrar.cpp:484] Successfully updated the
> >> 'registry' in 58.029056ms
> >> I0329 06:24:26.322201 32304 registrar.cpp:370] Successfully recovered
> >> registrar
> >> I0329 06:24:26.322477 32304 log.cpp:702] Attempting to truncate the log
> >> to 1
> >> I0329 06:24:26.323202 32309 master.cpp:1634] Recovered 0 slaves from the
> >> Registry (131B) ; allowing 10mins for slaves to re-register
> >> I0329 06:24:26.323429 32309 hierarchical.cpp:171] Skipping recovery of
> >> hierarchical allocator: nothing to recover
> >> I0329 06:24:26.323717 32296 coordinator.cpp:348] Coordinator attempting
> >> to write TRUNCATE action at position 2
> >> I0329 06:24:26.325094 32296 replica.cpp:537] Replica received write
> >> request for position 2 from (16417)@172.17.0.2:44577
> >> I0329 06:24:26.344977 32296 leveldb.cpp:341] Persisting action (16
> bytes)
> >> to leveldb took 19.869767ms
> >> I0329 06:24:26.345098 32296 replica.cpp:712] Persisted action at 2
> >> I0329 06:24:26.346740 32296 replica.cpp:691] Replica received learned
> >> notice for position 2 from @0.0.0.0:0
> >> I0329 06:24:26.370178 32296 leveldb.cpp:341] Persisting action (18
> bytes)
> >> to leveldb took 23.433093ms
> >> I0329 06:24:26.370374 32296 leveldb.cpp:399] Deleting ~1 keys from
> >> leveldb took 101580ns
> >> I0329 06:24:26.370405 32296 replica.cpp:712] Persisted action at 2
> >> I0329 06:24:26.370445 32296 replica.cpp:697] Replica learned TRUNCATE
> >> action at position 2
> >> I0329 06:24:26.375056 32278 scheduler.cpp:172] Version: 0.29.0
> >> I0329 06:24:26.376142 32296 scheduler.cpp:438] New master detected at
> >> master@172.17.0.2:44577
> >> I0329 06:24:26.378262 32304 scheduler.cpp:338] Connected with the master
> >> at http://172.17.0.2:44577/master/api/v1/scheduler
> >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
> >> 06:24:26.379989 32298 scheduler.cpp:231] Sending SUBSCRIBE call to
> >> http://172.17.0.2:44577/master/api/v1/scheduler
> >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
> >> 06:24:26.381155 32304 process.cpp:3136] Handling HTTP event for process
> >> 'master' with path: '/master/api/v1/scheduler'
> >> I0329 06:24:26.381928 32297 http.cpp:313] HTTP POST for
> >> /master/api/v1/scheduler from 172.17.0.2:60727
> >> I0329 06:24:26.382292 32297 master.cpp:2088] Received subscription
> >> request for HTTP framework 'default'
> >> I0329 06:24:26.382323 32297 master.cpp:1865] Authorizing framework
> >> principal 'test-principal' to receive offers for role '*'
> >> I0329 06:24:26.383196 32297 master.cpp:2179] Subscribing framework
> >> 'default' with checkpointing disabled and capabilities [  ]
> >> I0329 06:24:26.384454 32297 hierarchical.cpp:266] Added framework
> >> c0df3402-8c18-4794-aea1-d0fc2093455d-0000
> >> I0329 06:24:26.384557 32297 hierarchical.cpp:1490] No resources
> available
> >> to allocate!
> >> I0329 06:24:26.384598 32297 hierarchical.cpp:1585] No inverse offers to
> >> send out!
> >> I0329 06:24:26.384667 32297 hierarchical.cpp:1141] Performed allocation
> >> for 0 slaves in 195737ns
> >> I0329 06:24:26.384935 32297 master.hpp:1715] Sending heartbeat to
> >> c0df3402-8c18-4794-aea1-d0fc2093455d-0000
> >> I0329 06:24:26.385965 32306 scheduler.cpp:629] Enqueuing event
> SUBSCRIBED
> >> received from http://172.17.0.2:44577/master/api/v1/scheduler
> >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
> >> 06:24:26.386927 32306 scheduler.cpp:629] Enqueuing event HEARTBEAT
> received
> >> from http://172.17.0.2:44577/master/api/v1/scheduler
> >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
> >> 06:24:26.388162 32301 scheduler.cpp:231] Sending REQUEST call to
> >> http://172.17.0.2:44577/master/api/v1/scheduler
> >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
> >> 06:24:26.389529 32301 process.cpp:3136] Handling HTTP event for process
> >> 'master' with path: '/master/api/v1/scheduler'
> >> I0329 06:24:26.390229 32300 http.cpp:313] HTTP POST for
> >> /master/api/v1/scheduler from 172.17.0.2:60728
> >> I0329 06:24:26.390472 32300 master.cpp:2785] Processing REQUEST call for
> >> framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
> >> I0329 06:24:26.390704 32303 hierarchical.cpp:592] Received resource
> >> request from framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000
> >> I0329 06:24:26.394057 32305 master.cpp:1269] Framework
> >> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default) disconnected
> >> I0329 06:24:26.394111 32305 master.cpp:2642] Disconnecting framework
> >> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
> >> I0329 06:24:26.394139 32305 master.cpp:2666] Deactivating framework
> >> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
> >> I0329 06:24:26.394261 32305 master.cpp:1293] Giving framework
> >> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default) 0ns to failover
> >> I0329 06:24:26.394523 32305 hierarchical.cpp:378] Deactivated framework
> >> c0df3402-8c18-4794-aea1-d0fc2093455d-0000
> >> I0329 06:24:26.395911 32305 master.cpp:5360] Framework failover timeout,
> >> removing framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
> >> I0329 06:24:26.395953 32305 master.cpp:6093] Removing framework
> >> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
> >> I0329 06:24:26.396348 32305 hierarchical.cpp:329] Removed framework
> >> c0df3402-8c18-4794-aea1-d0fc2093455d-0000
> >> I0329 06:24:26.404429 32278 master.cpp:1083] Master terminating
> >> [       OK ] ContentType/SchedulerTest.Request/0 (666 ms)
> >> [ RUN      ] ContentType/SchedulerTest.Request/1
> >> I0329 06:24:26.430703 32278 cluster.cpp:139] Creating default 'local'
> >> authorizer
> >> I0329 06:24:26.515738 32278 leveldb.cpp:174] Opened db in 84.58043ms
> >> I0329 06:24:26.541720 32278 leveldb.cpp:181] Compacted db in 25.941366ms
> >> I0329 06:24:26.541827 32278 leveldb.cpp:196] Created db iterator in
> >> 35061ns
> >> I0329 06:24:26.541844 32278 leveldb.cpp:202] Seeked to beginning of db
> in
> >> 9132ns
> >> I0329 06:24:26.541856 32278 leveldb.cpp:271] Iterated through 0 keys in
> >> the db in 6113ns
> >> I0329 06:24:26.541901 32278 replica.cpp:779] Replica recovered with log
> >> positions 0 -> 0 with 1 holes and 0 unlearned
> >> I0329 06:24:26.542896 32309 recover.cpp:447] Starting replica recovery
> >> I0329 06:24:26.543231 32309 recover.cpp:473] Replica is in EMPTY status
> >> I0329 06:24:26.544759 32302 replica.cpp:673] Replica in EMPTY status
> >> received a broadcasted recover request from (16425)@172.17.0.2:44577
> >> I0329 06:24:26.545496 32309 recover.cpp:193] Received a recover response
> >> from a replica in EMPTY status
> >> I0329 06:24:26.546010 32309 recover.cpp:564] Updating replica status to
> >> STARTING
> >> I0329 06:24:26.550267 32300 master.cpp:376] Master
> >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c (58e403dcc250) started on
> >> 172.17.0.2:44577
> >> I0329 06:24:26.550334 32300 master.cpp:378] Flags at startup: --acls=""
> >> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> >> --authenticate="false" --authenticate_http="true"
> >> --authenticate_slaves="true" --authenticators="crammd5"
> >> --authorizers="local" --credentials="/tmp/rgiyDU/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.29.0/_inst/share/mesos/webui"
> >> --work_dir="/tmp/rgiyDU/master" --zk_session_timeout="10secs"
> >> I0329 06:24:26.550730 32300 master.cpp:429] Master allowing
> >> unauthenticated frameworks to register
> >> I0329 06:24:26.550742 32300 master.cpp:432] Master only allowing
> >> authenticated slaves to register
> >> I0329 06:24:26.550750 32300 credentials.hpp:37] Loading credentials for
> >> authentication from '/tmp/rgiyDU/credentials'
> >> I0329 06:24:26.551120 32300 master.cpp:474] Using default 'crammd5'
> >> authenticator
> >> I0329 06:24:26.551282 32300 master.cpp:545] Using default 'basic' HTTP
> >> authenticator
> >> I0329 06:24:26.551408 32300 master.cpp:583] Authorization enabled
> >> I0329 06:24:26.553966 32301 whitelist_watcher.cpp:77] No whitelist given
> >> I0329 06:24:26.554941 32308 master.cpp:1826] The newly elected leader is
> >> master@172.17.0.2:44577 with id e055dc0c-92ec-4a53-9dad-b00b45fc8a0c
> >> I0329 06:24:26.555119 32308 master.cpp:1839] Elected as the leading
> >> master!
> >> I0329 06:24:26.555203 32308 master.cpp:1526] Recovering from registrar
> >> I0329 06:24:26.555528 32308 registrar.cpp:307] Recovering registrar
> >> I0329 06:24:26.556252 32300 hierarchical.cpp:144] Initialized
> >> hierarchical allocator process
> >> I0329 06:24:26.583319 32309 leveldb.cpp:304] Persisting metadata (8
> >> bytes) to leveldb took 37.070461ms
> >> I0329 06:24:26.583428 32309 replica.cpp:320] Persisted replica status to
> >> STARTING
> >> I0329 06:24:26.583833 32300 recover.cpp:473] Replica is in STARTING
> status
> >> I0329 06:24:26.585232 32307 replica.cpp:673] Replica in STARTING status
> >> received a broadcasted recover request from (16427)@172.17.0.2:44577
> >> I0329 06:24:26.585587 32299 recover.cpp:193] Received a recover response
> >> from a replica in STARTING status
> >> I0329 06:24:26.586372 32307 recover.cpp:564] Updating replica status to
> >> VOTING
> >> I0329 06:24:26.625116 32298 leveldb.cpp:304] Persisting metadata (8
> >> bytes) to leveldb took 38.529877ms
> >> I0329 06:24:26.625231 32298 replica.cpp:320] Persisted replica status to
> >> VOTING
> >> I0329 06:24:26.625517 32303 recover.cpp:578] Successfully joined the
> >> Paxos group
> >> I0329 06:24:26.625844 32303 recover.cpp:462] Recover process terminated
> >> I0329 06:24:26.626432 32311 log.cpp:659] Attempting to start the writer
> >> I0329 06:24:26.627914 32309 replica.cpp:493] Replica received implicit
> >> promise request from (16428)@172.17.0.2:44577 with proposal 1
> >> I0329 06:24:26.658563 32309 leveldb.cpp:304] Persisting metadata (8
> >> bytes) to leveldb took 30.632068ms
> >> I0329 06:24:26.658671 32309 replica.cpp:342] Persisted promised to 1
> >> I0329 06:24:26.659965 32299 coordinator.cpp:238] Coordinator attempting
> >> to fill missing positions
> >> I0329 06:24:26.661671 32307 replica.cpp:388] Replica received explicit
> >> promise request from (16429)@172.17.0.2:44577 for position 0 with
> >> proposal 2
> >> I0329 06:24:26.700374 32307 leveldb.cpp:341] Persisting action (8 bytes)
> >> to leveldb took 38.66346ms
> >> I0329 06:24:26.700486 32307 replica.cpp:712] Persisted action at 0
> >> I0329 06:24:26.702354 32301 replica.cpp:537] Replica received write
> >> request for position 0 from (16430)@172.17.0.2:44577
> >> I0329 06:24:26.702471 32301 leveldb.cpp:436] Reading position from
> >> leveldb took 60629ns
> >> I0329 06:24:26.733788 32301 leveldb.cpp:341] Persisting action (14
> bytes)
> >> to leveldb took 31.29248ms
> >> I0329 06:24:26.733875 32301 replica.cpp:712] Persisted action at 0
> >> I0329 06:24:26.734787 32307 replica.cpp:691] Replica received learned
> >> notice for position 0 from @0.0.0.0:0
> >> I0329 06:24:26.766201 32307 leveldb.cpp:341] Persisting action (16
> bytes)
> >> to leveldb took 31.401125ms
> >> I0329 06:24:26.766286 32307 replica.cpp:712] Persisted action at 0
> >> I0329 06:24:26.766320 32307 replica.cpp:697] Replica learned NOP action
> >> at position 0
> >> I0329 06:24:26.767251 32301 log.cpp:675] Writer started with ending
> >> position 0
> >> I0329 06:24:26.768996 32298 leveldb.cpp:436] Reading position from
> >> leveldb took 111416ns
> >> I0329 06:24:26.770465 32298 registrar.cpp:340] Successfully fetched the
> >> registry (0B) in 214.683136ms
> >> I0329 06:24:26.770622 32298 registrar.cpp:439] Applied 1 operations in
> >> 54730ns; attempting to update the 'registry'
> >> I0329 06:24:26.772197 32298 log.cpp:683] Attempting to append 170 bytes
> >> to the log
> >> I0329 06:24:26.772478 32299 coordinator.cpp:348] Coordinator attempting
> >> to write APPEND action at position 1
> >> I0329 06:24:26.773361 32302 replica.cpp:537] Replica received write
> >> request for position 1 from (16431)@172.17.0.2:44577
> >> I0329 06:24:26.812733 32302 leveldb.cpp:341] Persisting action (189
> >> bytes) to leveldb took 39.346476ms
> >> I0329 06:24:26.812857 32302 replica.cpp:712] Persisted action at 1
> >> I0329 06:24:26.813958 32299 replica.cpp:691] Replica received learned
> >> notice for position 1 from @0.0.0.0:0
> >> I0329 06:24:26.837826 32299 leveldb.cpp:341] Persisting action (191
> >> bytes) to leveldb took 23.866792ms
> >> I0329 06:24:26.837930 32299 replica.cpp:712] Persisted action at 1
> >> I0329 06:24:26.837967 32299 replica.cpp:697] Replica learned APPEND
> >> action at position 1
> >> I0329 06:24:26.839619 32311 registrar.cpp:484] Successfully updated the
> >> 'registry' in 68.916992ms
> >> I0329 06:24:26.839851 32311 registrar.cpp:370] Successfully recovered
> >> registrar
> >> I0329 06:24:26.839921 32300 log.cpp:702] Attempting to truncate the log
> >> to 1
> >> I0329 06:24:26.840009 32299 coordinator.cpp:348] Coordinator attempting
> >> to write TRUNCATE action at position 2
> >> I0329 06:24:26.840361 32300 master.cpp:1634] Recovered 0 slaves from the
> >> Registry (131B) ; allowing 10mins for slaves to re-register
> >> I0329 06:24:26.840445 32305 hierarchical.cpp:171] Skipping recovery of
> >> hierarchical allocator: nothing to recover
> >> I0329 06:24:26.841119 32306 replica.cpp:537] Replica received write
> >> request for position 2 from (16432)@172.17.0.2:44577
> >> I0329 06:24:26.863555 32306 leveldb.cpp:341] Persisting action (16
> bytes)
> >> to leveldb took 22.418343ms
> >> I0329 06:24:26.863641 32306 replica.cpp:712] Persisted action at 2
> >> I0329 06:24:26.865054 32306 replica.cpp:691] Replica received learned
> >> notice for position 2 from @0.0.0.0:0
> >> I0329 06:24:26.883360 32306 leveldb.cpp:341] Persisting action (18
> bytes)
> >> to leveldb took 18.305162ms
> >> I0329 06:24:26.883512 32306 leveldb.cpp:399] Deleting ~1 keys from
> >> leveldb took 83579ns
> >> I0329 06:24:26.883538 32306 replica.cpp:712] Persisted action at 2
> >> I0329 06:24:26.883570 32306 replica.cpp:697] Replica learned TRUNCATE
> >> action at position 2
> >> I0329 06:24:26.891973 32278 scheduler.cpp:172] Version: 0.29.0
> >> I0329 06:24:26.892935 32302 scheduler.cpp:438] New master detected at
> >> master@172.17.0.2:44577
> >> I0329 06:24:26.894947 32298 scheduler.cpp:338] Connected with the master
> >> at http://172.17.0.2:44577/master/api/v1/scheduler
> >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
> >> 06:24:26.896307 32309 scheduler.cpp:231] Sending SUBSCRIBE call to
> >> http://172.17.0.2:44577/master/api/v1/scheduler
> >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
> >> 06:24:26.897629 32309 process.cpp:3136] Handling HTTP event for process
> >> 'master' with path: '/master/api/v1/scheduler'
> >> I0329 06:24:26.898205 32309 http.cpp:313] HTTP POST for
> >> /master/api/v1/scheduler from 172.17.0.2:60732
> >> I0329 06:24:26.898597 32309 master.cpp:2088] Received subscription
> >> request for HTTP framework 'default'
> >> I0329 06:24:26.898622 32309 master.cpp:1865] Authorizing framework
> >> principal 'test-principal' to receive offers for role '*'
> >> I0329 06:24:26.899363 32309 master.cpp:2179] Subscribing framework
> >> 'default' with checkpointing disabled and capabilities [  ]
> >> I0329 06:24:26.900554 32309 hierarchical.cpp:266] Added framework
> >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
> >> I0329 06:24:26.900652 32309 hierarchical.cpp:1490] No resources
> available
> >> to allocate!
> >> I0329 06:24:26.900890 32303 master.hpp:1715] Sending heartbeat to
> >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
> >> I0329 06:24:26.902096 32304 scheduler.cpp:629] Enqueuing event
> SUBSCRIBED
> >> received from http://172.17.0.2:44577/master/api/v1/scheduler
> >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
> >> 06:24:26.902498 32304 scheduler.cpp:629] Enqueuing event HEARTBEAT
> received
> >> from http://172.17.0.2:44577/master/api/v1/scheduler
> >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
> >> 06:24:26.902828 32309 hierarchical.cpp:1585] No inverse offers to send
> out!
> >> I0329 06:24:26.902901 32309 hierarchical.cpp:1141] Performed allocation
> >> for 0 slaves in 2.330913ms
> >> I0329 06:24:26.903455 32308 scheduler.cpp:231] Sending REQUEST call to
> >> http://172.17.0.2:44577/master/api/v1/scheduler
> >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
> >> 06:24:26.904709 32302 process.cpp:3136] Handling HTTP event for process
> >> 'master' with path: '/master/api/v1/scheduler'
> >> I0329 06:24:26.905304 32302 http.cpp:313] HTTP POST for
> >> /master/api/v1/scheduler from 172.17.0.2:60733
> >> I0329 06:24:26.905647 32302 master.cpp:2785] Processing REQUEST call for
> >> framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
> >> I0329 06:24:26.905892 32302 hierarchical.cpp:592] Received resource
> >> request from framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
> >> I0329 06:24:26.908712 32304 master.cpp:1269] Framework
> >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default) disconnected
> >> I0329 06:24:26.908759 32304 master.cpp:2642] Disconnecting framework
> >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
> >> I0329 06:24:26.908783 32304 master.cpp:2666] Deactivating framework
> >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
> >> I0329 06:24:26.908882 32304 master.cpp:1293] Giving framework
> >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default) 0ns to failover
> >> I0329 06:24:26.909106 32304 hierarchical.cpp:378] Deactivated framework
> >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
> >> I0329 06:24:26.910423 32304 master.cpp:5360] Framework failover timeout,
> >> removing framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
> >> I0329 06:24:26.910450 32304 master.cpp:6093] Removing framework
> >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
> >> I0329 06:24:26.910758 32304 hierarchical.cpp:329] Removed framework
> >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
> >> I0329 06:24:26.919173 32278 master.cpp:1083] Master terminating
> >> [       OK ] ContentType/SchedulerTest.Request/1 (499 ms)
> >> [----------] 26 tests from ContentType/SchedulerTest (19532 ms total)
> >>
> >> [----------] Global test environment tear-down
> >> E0329 06:24:27.207061 32278 environment.cpp:778] Failed to remove
> >> '/tmp/LogZooKeeperTest_WriteRead_isx6ON': No such file or directory
> >> E0329 06:24:27.207288 32278 environment.cpp:778] Failed to remove
> >> '/tmp/LogZooKeeperTest_LostZooKeeper_UHzb62': No such file or directory
> >> [==========] 1052 tests from 132 test cases ran. (788517 ms total)
> >> [  PASSED  ] 1051 tests.
> >> [  FAILED  ] 1 test, listed below:
> >> [  FAILED  ] ContainerLoggerTest.LOGROTATE_RotateInSandbox
> >>
> >>  1 FAILED TEST
> >>   YOU HAVE 6 DISABLED TESTS
> >>
> >> make[4]: *** [check-local] Error 1
> >> make[4]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> >> make[3]: *** [check-am] Error 2
> >> make[3]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> >> make[2]: *** [check] Error 2
> >> make[2]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> >> make[1]: Leaving directory `/mesos/mesos-0.29.0/_build'
> >> make[1]: *** [check-recursive] Error 1
> >> make: *** [distcheck] Error 1
> >> + docker rmi mesos-1459230127-13906
> >> Untagged: mesos-1459230127-13906:latest
> >> Deleted:
> cacb8f6afe4d7fdbe1ac7bf1ad0338181413f3a81b5b1c5cf56bf2667f810aad
> >> Deleted:
> f48e36119876751f898378fa38c0e9cc8f01f3566284450b1e917b855f4520c5
> >> Deleted:
> 90526e7a88d474bb45f1edb44c7cc3eea0151ecca4be9ec0d3765d1888fa8315
> >> Deleted:
> 76e7df84fa713c1a9786f5f1f4c4dda9be08c32e292c151e01049bb1cbcd448c
> >> Deleted:
> cac845a110e827f30f8bcde5084d1a8b2b252fd7e3593d123763a75d1d9f7af8
> >> Deleted:
> 2304e9792ef33b93a77f035160b9de70501c135bddfe18a6f16023f51faeb60a
> >> Deleted:
> 6eb7b0792e8661dd7e8d1d95eb8549bda73ce4da64e2274240ebf39d73ee0ffb
> >> Deleted:
> cb90fa476408199deb8e11824723b16bc05ca545548b773aa30a49f2295f8f77
> >> Deleted:
> f7d306edca1ab89b22abc8e15bd4bac995e67183c4620cf654c15721598f9c4d
> >> Deleted:
> 76558ca548aa164ffe882d74281b3d03f6be8cc76b5b71b7b6cee7d1e95f9677
> >> Deleted:
> a236590f9a5e81a39c2acf4e88ba0dc7706b44b6093ddb6a37d5809c6e27f3e7
> >> Deleted:
> 6507f80835107a1b45ec2612528378e6ea33acc3ab14e635cd9bb4e6cf217073
> >> Deleted:
> 8fbd0d2550c193ccb5107a9a13540cd1646e18d01286449d09cb9c7184d77186
> >> Deleted:
> bc9b5531d8a17a788ca2cec0731fdc7ef3f3e7d3a0e6c8c10e5e20dbc38a60d5
> >> Deleted:
> b005ae3936a4887eeee6a9ce4d5a41ba3e61b8ea4267ed01e09a3cbdca026684
> >> Deleted:
> 80c1f8ce2529191ea73a5ab2339172c0c801f7e56bd326b240015eae5c680502
> >> Build step 'Execute shell' marked build as failure
> >>
> >
> >
>

Re: Build failed in Jenkins: Mesos » clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1888

Posted by Joseph Wu <jo...@mesosphere.io>.
Tracked here: https://issues.apache.org/jira/browse/MESOS-4961

On Tue, Mar 29, 2016 at 11:22 AM, Benjamin Mahler <bm...@apache.org>
wrote:

> +joseph
>
> On Mon, Mar 28, 2016 at 11:32 PM, Apache Jenkins Server <
> jenkins@builds.apache.org> wrote:
>
>> See <
>> https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1888/changes
>> >
>>
>> Changes:
>>
>> [yujie.jay] Adapted port_mapping isolator with missing subprocess
>> parameter.
>>
>> [yujie.jay] Fixed typo in subprocess doxygen comments.
>>
>> ------------------------------------------
>> [...truncated 178901 lines...]
>> I0329 06:24:26.085312 32305 master.cpp:378] Flags at startup: --acls=""
>> --allocation_interval="1secs" --allocator="HierarchicalDRF"
>> --authenticate="false" --authenticate_http="true"
>> --authenticate_slaves="true" --authenticators="crammd5"
>> --authorizers="local" --credentials="/tmp/h70a8A/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.29.0/_inst/share/mesos/webui"
>> --work_dir="/tmp/h70a8A/master" --zk_session_timeout="10secs"
>> I0329 06:24:26.085846 32305 master.cpp:429] Master allowing
>> unauthenticated frameworks to register
>> I0329 06:24:26.085932 32305 master.cpp:432] Master only allowing
>> authenticated slaves to register
>> I0329 06:24:26.086014 32305 credentials.hpp:37] Loading credentials for
>> authentication from '/tmp/h70a8A/credentials'
>> I0329 06:24:26.089220 32305 master.cpp:474] Using default 'crammd5'
>> authenticator
>> I0329 06:24:26.089391 32305 master.cpp:545] Using default 'basic' HTTP
>> authenticator
>> I0329 06:24:26.089530 32305 master.cpp:583] Authorization enabled
>> I0329 06:24:26.092001 32305 hierarchical.cpp:144] Initialized
>> hierarchical allocator process
>> I0329 06:24:26.092093 32305 whitelist_watcher.cpp:77] No whitelist given
>> I0329 06:24:26.093214 32310 master.cpp:1826] The newly elected leader is
>> master@172.17.0.2:44577 with id c0df3402-8c18-4794-aea1-d0fc2093455d
>> I0329 06:24:26.093361 32310 master.cpp:1839] Elected as the leading
>> master!
>> I0329 06:24:26.093479 32310 master.cpp:1526] Recovering from registrar
>> I0329 06:24:26.093793 32302 registrar.cpp:307] Recovering registrar
>> I0329 06:24:26.097321 32299 leveldb.cpp:304] Persisting metadata (8
>> bytes) to leveldb took 28.543546ms
>> I0329 06:24:26.097383 32299 replica.cpp:320] Persisted replica status to
>> STARTING
>> I0329 06:24:26.097652 32299 recover.cpp:473] Replica is in STARTING status
>> I0329 06:24:26.099536 32300 replica.cpp:673] Replica in STARTING status
>> received a broadcasted recover request from (16412)@172.17.0.2:44577
>> I0329 06:24:26.100143 32300 recover.cpp:193] Received a recover response
>> from a replica in STARTING status
>> I0329 06:24:26.100862 32300 recover.cpp:564] Updating replica status to
>> VOTING
>> I0329 06:24:26.131098 32300 leveldb.cpp:304] Persisting metadata (8
>> bytes) to leveldb took 30.047383ms
>> I0329 06:24:26.131202 32300 replica.cpp:320] Persisted replica status to
>> VOTING
>> I0329 06:24:26.131471 32300 recover.cpp:578] Successfully joined the
>> Paxos group
>> I0329 06:24:26.131674 32300 recover.cpp:462] Recover process terminated
>> I0329 06:24:26.132522 32300 log.cpp:659] Attempting to start the writer
>> I0329 06:24:26.134305 32300 replica.cpp:493] Replica received implicit
>> promise request from (16413)@172.17.0.2:44577 with proposal 1
>> I0329 06:24:26.164098 32300 leveldb.cpp:304] Persisting metadata (8
>> bytes) to leveldb took 29.777838ms
>> I0329 06:24:26.164202 32300 replica.cpp:342] Persisted promised to 1
>> I0329 06:24:26.174093 32307 coordinator.cpp:238] Coordinator attempting
>> to fill missing positions
>> I0329 06:24:26.177204 32307 replica.cpp:388] Replica received explicit
>> promise request from (16414)@172.17.0.2:44577 for position 0 with
>> proposal 2
>> I0329 06:24:26.202256 32307 leveldb.cpp:341] Persisting action (8 bytes)
>> to leveldb took 24.828611ms
>> I0329 06:24:26.202365 32307 replica.cpp:712] Persisted action at 0
>> I0329 06:24:26.204294 32307 replica.cpp:537] Replica received write
>> request for position 0 from (16415)@172.17.0.2:44577
>> I0329 06:24:26.204396 32307 leveldb.cpp:436] Reading position from
>> leveldb took 63731ns
>> I0329 06:24:26.227409 32307 leveldb.cpp:341] Persisting action (14 bytes)
>> to leveldb took 22.997586ms
>> I0329 06:24:26.227517 32307 replica.cpp:712] Persisted action at 0
>> I0329 06:24:26.233665 32299 replica.cpp:691] Replica received learned
>> notice for position 0 from @0.0.0.0:0
>> I0329 06:24:26.252598 32299 leveldb.cpp:341] Persisting action (16 bytes)
>> to leveldb took 18.804081ms
>> I0329 06:24:26.252712 32299 replica.cpp:712] Persisted action at 0
>> I0329 06:24:26.252753 32299 replica.cpp:697] Replica learned NOP action
>> at position 0
>> I0329 06:24:26.254039 32299 log.cpp:675] Writer started with ending
>> position 0
>> I0329 06:24:26.255581 32299 leveldb.cpp:436] Reading position from
>> leveldb took 100584ns
>> I0329 06:24:26.263558 32306 registrar.cpp:340] Successfully fetched the
>> registry (0B) in 169.595904ms
>> I0329 06:24:26.263902 32306 registrar.cpp:439] Applied 1 operations in
>> 60146ns; attempting to update the 'registry'
>> I0329 06:24:26.266656 32304 log.cpp:683] Attempting to append 170 bytes
>> to the log
>> I0329 06:24:26.266984 32304 coordinator.cpp:348] Coordinator attempting
>> to write APPEND action at position 1
>> I0329 06:24:26.268291 32304 replica.cpp:537] Replica received write
>> request for position 1 from (16416)@172.17.0.2:44577
>> I0329 06:24:26.294421 32304 leveldb.cpp:341] Persisting action (189
>> bytes) to leveldb took 26.109178ms
>> I0329 06:24:26.294524 32304 replica.cpp:712] Persisted action at 1
>> I0329 06:24:26.296066 32304 replica.cpp:691] Replica received learned
>> notice for position 1 from @0.0.0.0:0
>> I0329 06:24:26.319604 32304 leveldb.cpp:341] Persisting action (191
>> bytes) to leveldb took 23.539377ms
>> I0329 06:24:26.319721 32304 replica.cpp:712] Persisted action at 1
>> I0329 06:24:26.319761 32304 replica.cpp:697] Replica learned APPEND
>> action at position 1
>> I0329 06:24:26.322026 32304 registrar.cpp:484] Successfully updated the
>> 'registry' in 58.029056ms
>> I0329 06:24:26.322201 32304 registrar.cpp:370] Successfully recovered
>> registrar
>> I0329 06:24:26.322477 32304 log.cpp:702] Attempting to truncate the log
>> to 1
>> I0329 06:24:26.323202 32309 master.cpp:1634] Recovered 0 slaves from the
>> Registry (131B) ; allowing 10mins for slaves to re-register
>> I0329 06:24:26.323429 32309 hierarchical.cpp:171] Skipping recovery of
>> hierarchical allocator: nothing to recover
>> I0329 06:24:26.323717 32296 coordinator.cpp:348] Coordinator attempting
>> to write TRUNCATE action at position 2
>> I0329 06:24:26.325094 32296 replica.cpp:537] Replica received write
>> request for position 2 from (16417)@172.17.0.2:44577
>> I0329 06:24:26.344977 32296 leveldb.cpp:341] Persisting action (16 bytes)
>> to leveldb took 19.869767ms
>> I0329 06:24:26.345098 32296 replica.cpp:712] Persisted action at 2
>> I0329 06:24:26.346740 32296 replica.cpp:691] Replica received learned
>> notice for position 2 from @0.0.0.0:0
>> I0329 06:24:26.370178 32296 leveldb.cpp:341] Persisting action (18 bytes)
>> to leveldb took 23.433093ms
>> I0329 06:24:26.370374 32296 leveldb.cpp:399] Deleting ~1 keys from
>> leveldb took 101580ns
>> I0329 06:24:26.370405 32296 replica.cpp:712] Persisted action at 2
>> I0329 06:24:26.370445 32296 replica.cpp:697] Replica learned TRUNCATE
>> action at position 2
>> I0329 06:24:26.375056 32278 scheduler.cpp:172] Version: 0.29.0
>> I0329 06:24:26.376142 32296 scheduler.cpp:438] New master detected at
>> master@172.17.0.2:44577
>> I0329 06:24:26.378262 32304 scheduler.cpp:338] Connected with the master
>> at http://172.17.0.2:44577/master/api/v1/scheduler
>> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
>> 06:24:26.379989 32298 scheduler.cpp:231] Sending SUBSCRIBE call to
>> http://172.17.0.2:44577/master/api/v1/scheduler
>> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
>> 06:24:26.381155 32304 process.cpp:3136] Handling HTTP event for process
>> 'master' with path: '/master/api/v1/scheduler'
>> I0329 06:24:26.381928 32297 http.cpp:313] HTTP POST for
>> /master/api/v1/scheduler from 172.17.0.2:60727
>> I0329 06:24:26.382292 32297 master.cpp:2088] Received subscription
>> request for HTTP framework 'default'
>> I0329 06:24:26.382323 32297 master.cpp:1865] Authorizing framework
>> principal 'test-principal' to receive offers for role '*'
>> I0329 06:24:26.383196 32297 master.cpp:2179] Subscribing framework
>> 'default' with checkpointing disabled and capabilities [  ]
>> I0329 06:24:26.384454 32297 hierarchical.cpp:266] Added framework
>> c0df3402-8c18-4794-aea1-d0fc2093455d-0000
>> I0329 06:24:26.384557 32297 hierarchical.cpp:1490] No resources available
>> to allocate!
>> I0329 06:24:26.384598 32297 hierarchical.cpp:1585] No inverse offers to
>> send out!
>> I0329 06:24:26.384667 32297 hierarchical.cpp:1141] Performed allocation
>> for 0 slaves in 195737ns
>> I0329 06:24:26.384935 32297 master.hpp:1715] Sending heartbeat to
>> c0df3402-8c18-4794-aea1-d0fc2093455d-0000
>> I0329 06:24:26.385965 32306 scheduler.cpp:629] Enqueuing event SUBSCRIBED
>> received from http://172.17.0.2:44577/master/api/v1/scheduler
>> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
>> 06:24:26.386927 32306 scheduler.cpp:629] Enqueuing event HEARTBEAT received
>> from http://172.17.0.2:44577/master/api/v1/scheduler
>> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
>> 06:24:26.388162 32301 scheduler.cpp:231] Sending REQUEST call to
>> http://172.17.0.2:44577/master/api/v1/scheduler
>> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
>> 06:24:26.389529 32301 process.cpp:3136] Handling HTTP event for process
>> 'master' with path: '/master/api/v1/scheduler'
>> I0329 06:24:26.390229 32300 http.cpp:313] HTTP POST for
>> /master/api/v1/scheduler from 172.17.0.2:60728
>> I0329 06:24:26.390472 32300 master.cpp:2785] Processing REQUEST call for
>> framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
>> I0329 06:24:26.390704 32303 hierarchical.cpp:592] Received resource
>> request from framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000
>> I0329 06:24:26.394057 32305 master.cpp:1269] Framework
>> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default) disconnected
>> I0329 06:24:26.394111 32305 master.cpp:2642] Disconnecting framework
>> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
>> I0329 06:24:26.394139 32305 master.cpp:2666] Deactivating framework
>> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
>> I0329 06:24:26.394261 32305 master.cpp:1293] Giving framework
>> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default) 0ns to failover
>> I0329 06:24:26.394523 32305 hierarchical.cpp:378] Deactivated framework
>> c0df3402-8c18-4794-aea1-d0fc2093455d-0000
>> I0329 06:24:26.395911 32305 master.cpp:5360] Framework failover timeout,
>> removing framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
>> I0329 06:24:26.395953 32305 master.cpp:6093] Removing framework
>> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
>> I0329 06:24:26.396348 32305 hierarchical.cpp:329] Removed framework
>> c0df3402-8c18-4794-aea1-d0fc2093455d-0000
>> I0329 06:24:26.404429 32278 master.cpp:1083] Master terminating
>> [       OK ] ContentType/SchedulerTest.Request/0 (666 ms)
>> [ RUN      ] ContentType/SchedulerTest.Request/1
>> I0329 06:24:26.430703 32278 cluster.cpp:139] Creating default 'local'
>> authorizer
>> I0329 06:24:26.515738 32278 leveldb.cpp:174] Opened db in 84.58043ms
>> I0329 06:24:26.541720 32278 leveldb.cpp:181] Compacted db in 25.941366ms
>> I0329 06:24:26.541827 32278 leveldb.cpp:196] Created db iterator in
>> 35061ns
>> I0329 06:24:26.541844 32278 leveldb.cpp:202] Seeked to beginning of db in
>> 9132ns
>> I0329 06:24:26.541856 32278 leveldb.cpp:271] Iterated through 0 keys in
>> the db in 6113ns
>> I0329 06:24:26.541901 32278 replica.cpp:779] Replica recovered with log
>> positions 0 -> 0 with 1 holes and 0 unlearned
>> I0329 06:24:26.542896 32309 recover.cpp:447] Starting replica recovery
>> I0329 06:24:26.543231 32309 recover.cpp:473] Replica is in EMPTY status
>> I0329 06:24:26.544759 32302 replica.cpp:673] Replica in EMPTY status
>> received a broadcasted recover request from (16425)@172.17.0.2:44577
>> I0329 06:24:26.545496 32309 recover.cpp:193] Received a recover response
>> from a replica in EMPTY status
>> I0329 06:24:26.546010 32309 recover.cpp:564] Updating replica status to
>> STARTING
>> I0329 06:24:26.550267 32300 master.cpp:376] Master
>> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c (58e403dcc250) started on
>> 172.17.0.2:44577
>> I0329 06:24:26.550334 32300 master.cpp:378] Flags at startup: --acls=""
>> --allocation_interval="1secs" --allocator="HierarchicalDRF"
>> --authenticate="false" --authenticate_http="true"
>> --authenticate_slaves="true" --authenticators="crammd5"
>> --authorizers="local" --credentials="/tmp/rgiyDU/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.29.0/_inst/share/mesos/webui"
>> --work_dir="/tmp/rgiyDU/master" --zk_session_timeout="10secs"
>> I0329 06:24:26.550730 32300 master.cpp:429] Master allowing
>> unauthenticated frameworks to register
>> I0329 06:24:26.550742 32300 master.cpp:432] Master only allowing
>> authenticated slaves to register
>> I0329 06:24:26.550750 32300 credentials.hpp:37] Loading credentials for
>> authentication from '/tmp/rgiyDU/credentials'
>> I0329 06:24:26.551120 32300 master.cpp:474] Using default 'crammd5'
>> authenticator
>> I0329 06:24:26.551282 32300 master.cpp:545] Using default 'basic' HTTP
>> authenticator
>> I0329 06:24:26.551408 32300 master.cpp:583] Authorization enabled
>> I0329 06:24:26.553966 32301 whitelist_watcher.cpp:77] No whitelist given
>> I0329 06:24:26.554941 32308 master.cpp:1826] The newly elected leader is
>> master@172.17.0.2:44577 with id e055dc0c-92ec-4a53-9dad-b00b45fc8a0c
>> I0329 06:24:26.555119 32308 master.cpp:1839] Elected as the leading
>> master!
>> I0329 06:24:26.555203 32308 master.cpp:1526] Recovering from registrar
>> I0329 06:24:26.555528 32308 registrar.cpp:307] Recovering registrar
>> I0329 06:24:26.556252 32300 hierarchical.cpp:144] Initialized
>> hierarchical allocator process
>> I0329 06:24:26.583319 32309 leveldb.cpp:304] Persisting metadata (8
>> bytes) to leveldb took 37.070461ms
>> I0329 06:24:26.583428 32309 replica.cpp:320] Persisted replica status to
>> STARTING
>> I0329 06:24:26.583833 32300 recover.cpp:473] Replica is in STARTING status
>> I0329 06:24:26.585232 32307 replica.cpp:673] Replica in STARTING status
>> received a broadcasted recover request from (16427)@172.17.0.2:44577
>> I0329 06:24:26.585587 32299 recover.cpp:193] Received a recover response
>> from a replica in STARTING status
>> I0329 06:24:26.586372 32307 recover.cpp:564] Updating replica status to
>> VOTING
>> I0329 06:24:26.625116 32298 leveldb.cpp:304] Persisting metadata (8
>> bytes) to leveldb took 38.529877ms
>> I0329 06:24:26.625231 32298 replica.cpp:320] Persisted replica status to
>> VOTING
>> I0329 06:24:26.625517 32303 recover.cpp:578] Successfully joined the
>> Paxos group
>> I0329 06:24:26.625844 32303 recover.cpp:462] Recover process terminated
>> I0329 06:24:26.626432 32311 log.cpp:659] Attempting to start the writer
>> I0329 06:24:26.627914 32309 replica.cpp:493] Replica received implicit
>> promise request from (16428)@172.17.0.2:44577 with proposal 1
>> I0329 06:24:26.658563 32309 leveldb.cpp:304] Persisting metadata (8
>> bytes) to leveldb took 30.632068ms
>> I0329 06:24:26.658671 32309 replica.cpp:342] Persisted promised to 1
>> I0329 06:24:26.659965 32299 coordinator.cpp:238] Coordinator attempting
>> to fill missing positions
>> I0329 06:24:26.661671 32307 replica.cpp:388] Replica received explicit
>> promise request from (16429)@172.17.0.2:44577 for position 0 with
>> proposal 2
>> I0329 06:24:26.700374 32307 leveldb.cpp:341] Persisting action (8 bytes)
>> to leveldb took 38.66346ms
>> I0329 06:24:26.700486 32307 replica.cpp:712] Persisted action at 0
>> I0329 06:24:26.702354 32301 replica.cpp:537] Replica received write
>> request for position 0 from (16430)@172.17.0.2:44577
>> I0329 06:24:26.702471 32301 leveldb.cpp:436] Reading position from
>> leveldb took 60629ns
>> I0329 06:24:26.733788 32301 leveldb.cpp:341] Persisting action (14 bytes)
>> to leveldb took 31.29248ms
>> I0329 06:24:26.733875 32301 replica.cpp:712] Persisted action at 0
>> I0329 06:24:26.734787 32307 replica.cpp:691] Replica received learned
>> notice for position 0 from @0.0.0.0:0
>> I0329 06:24:26.766201 32307 leveldb.cpp:341] Persisting action (16 bytes)
>> to leveldb took 31.401125ms
>> I0329 06:24:26.766286 32307 replica.cpp:712] Persisted action at 0
>> I0329 06:24:26.766320 32307 replica.cpp:697] Replica learned NOP action
>> at position 0
>> I0329 06:24:26.767251 32301 log.cpp:675] Writer started with ending
>> position 0
>> I0329 06:24:26.768996 32298 leveldb.cpp:436] Reading position from
>> leveldb took 111416ns
>> I0329 06:24:26.770465 32298 registrar.cpp:340] Successfully fetched the
>> registry (0B) in 214.683136ms
>> I0329 06:24:26.770622 32298 registrar.cpp:439] Applied 1 operations in
>> 54730ns; attempting to update the 'registry'
>> I0329 06:24:26.772197 32298 log.cpp:683] Attempting to append 170 bytes
>> to the log
>> I0329 06:24:26.772478 32299 coordinator.cpp:348] Coordinator attempting
>> to write APPEND action at position 1
>> I0329 06:24:26.773361 32302 replica.cpp:537] Replica received write
>> request for position 1 from (16431)@172.17.0.2:44577
>> I0329 06:24:26.812733 32302 leveldb.cpp:341] Persisting action (189
>> bytes) to leveldb took 39.346476ms
>> I0329 06:24:26.812857 32302 replica.cpp:712] Persisted action at 1
>> I0329 06:24:26.813958 32299 replica.cpp:691] Replica received learned
>> notice for position 1 from @0.0.0.0:0
>> I0329 06:24:26.837826 32299 leveldb.cpp:341] Persisting action (191
>> bytes) to leveldb took 23.866792ms
>> I0329 06:24:26.837930 32299 replica.cpp:712] Persisted action at 1
>> I0329 06:24:26.837967 32299 replica.cpp:697] Replica learned APPEND
>> action at position 1
>> I0329 06:24:26.839619 32311 registrar.cpp:484] Successfully updated the
>> 'registry' in 68.916992ms
>> I0329 06:24:26.839851 32311 registrar.cpp:370] Successfully recovered
>> registrar
>> I0329 06:24:26.839921 32300 log.cpp:702] Attempting to truncate the log
>> to 1
>> I0329 06:24:26.840009 32299 coordinator.cpp:348] Coordinator attempting
>> to write TRUNCATE action at position 2
>> I0329 06:24:26.840361 32300 master.cpp:1634] Recovered 0 slaves from the
>> Registry (131B) ; allowing 10mins for slaves to re-register
>> I0329 06:24:26.840445 32305 hierarchical.cpp:171] Skipping recovery of
>> hierarchical allocator: nothing to recover
>> I0329 06:24:26.841119 32306 replica.cpp:537] Replica received write
>> request for position 2 from (16432)@172.17.0.2:44577
>> I0329 06:24:26.863555 32306 leveldb.cpp:341] Persisting action (16 bytes)
>> to leveldb took 22.418343ms
>> I0329 06:24:26.863641 32306 replica.cpp:712] Persisted action at 2
>> I0329 06:24:26.865054 32306 replica.cpp:691] Replica received learned
>> notice for position 2 from @0.0.0.0:0
>> I0329 06:24:26.883360 32306 leveldb.cpp:341] Persisting action (18 bytes)
>> to leveldb took 18.305162ms
>> I0329 06:24:26.883512 32306 leveldb.cpp:399] Deleting ~1 keys from
>> leveldb took 83579ns
>> I0329 06:24:26.883538 32306 replica.cpp:712] Persisted action at 2
>> I0329 06:24:26.883570 32306 replica.cpp:697] Replica learned TRUNCATE
>> action at position 2
>> I0329 06:24:26.891973 32278 scheduler.cpp:172] Version: 0.29.0
>> I0329 06:24:26.892935 32302 scheduler.cpp:438] New master detected at
>> master@172.17.0.2:44577
>> I0329 06:24:26.894947 32298 scheduler.cpp:338] Connected with the master
>> at http://172.17.0.2:44577/master/api/v1/scheduler
>> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
>> 06:24:26.896307 32309 scheduler.cpp:231] Sending SUBSCRIBE call to
>> http://172.17.0.2:44577/master/api/v1/scheduler
>> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
>> 06:24:26.897629 32309 process.cpp:3136] Handling HTTP event for process
>> 'master' with path: '/master/api/v1/scheduler'
>> I0329 06:24:26.898205 32309 http.cpp:313] HTTP POST for
>> /master/api/v1/scheduler from 172.17.0.2:60732
>> I0329 06:24:26.898597 32309 master.cpp:2088] Received subscription
>> request for HTTP framework 'default'
>> I0329 06:24:26.898622 32309 master.cpp:1865] Authorizing framework
>> principal 'test-principal' to receive offers for role '*'
>> I0329 06:24:26.899363 32309 master.cpp:2179] Subscribing framework
>> 'default' with checkpointing disabled and capabilities [  ]
>> I0329 06:24:26.900554 32309 hierarchical.cpp:266] Added framework
>> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
>> I0329 06:24:26.900652 32309 hierarchical.cpp:1490] No resources available
>> to allocate!
>> I0329 06:24:26.900890 32303 master.hpp:1715] Sending heartbeat to
>> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
>> I0329 06:24:26.902096 32304 scheduler.cpp:629] Enqueuing event SUBSCRIBED
>> received from http://172.17.0.2:44577/master/api/v1/scheduler
>> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
>> 06:24:26.902498 32304 scheduler.cpp:629] Enqueuing event HEARTBEAT received
>> from http://172.17.0.2:44577/master/api/v1/scheduler
>> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
>> 06:24:26.902828 32309 hierarchical.cpp:1585] No inverse offers to send out!
>> I0329 06:24:26.902901 32309 hierarchical.cpp:1141] Performed allocation
>> for 0 slaves in 2.330913ms
>> I0329 06:24:26.903455 32308 scheduler.cpp:231] Sending REQUEST call to
>> http://172.17.0.2:44577/master/api/v1/scheduler
>> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329>
>> 06:24:26.904709 32302 process.cpp:3136] Handling HTTP event for process
>> 'master' with path: '/master/api/v1/scheduler'
>> I0329 06:24:26.905304 32302 http.cpp:313] HTTP POST for
>> /master/api/v1/scheduler from 172.17.0.2:60733
>> I0329 06:24:26.905647 32302 master.cpp:2785] Processing REQUEST call for
>> framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
>> I0329 06:24:26.905892 32302 hierarchical.cpp:592] Received resource
>> request from framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
>> I0329 06:24:26.908712 32304 master.cpp:1269] Framework
>> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default) disconnected
>> I0329 06:24:26.908759 32304 master.cpp:2642] Disconnecting framework
>> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
>> I0329 06:24:26.908783 32304 master.cpp:2666] Deactivating framework
>> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
>> I0329 06:24:26.908882 32304 master.cpp:1293] Giving framework
>> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default) 0ns to failover
>> I0329 06:24:26.909106 32304 hierarchical.cpp:378] Deactivated framework
>> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
>> I0329 06:24:26.910423 32304 master.cpp:5360] Framework failover timeout,
>> removing framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
>> I0329 06:24:26.910450 32304 master.cpp:6093] Removing framework
>> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
>> I0329 06:24:26.910758 32304 hierarchical.cpp:329] Removed framework
>> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
>> I0329 06:24:26.919173 32278 master.cpp:1083] Master terminating
>> [       OK ] ContentType/SchedulerTest.Request/1 (499 ms)
>> [----------] 26 tests from ContentType/SchedulerTest (19532 ms total)
>>
>> [----------] Global test environment tear-down
>> E0329 06:24:27.207061 32278 environment.cpp:778] Failed to remove
>> '/tmp/LogZooKeeperTest_WriteRead_isx6ON': No such file or directory
>> E0329 06:24:27.207288 32278 environment.cpp:778] Failed to remove
>> '/tmp/LogZooKeeperTest_LostZooKeeper_UHzb62': No such file or directory
>> [==========] 1052 tests from 132 test cases ran. (788517 ms total)
>> [  PASSED  ] 1051 tests.
>> [  FAILED  ] 1 test, listed below:
>> [  FAILED  ] ContainerLoggerTest.LOGROTATE_RotateInSandbox
>>
>>  1 FAILED TEST
>>   YOU HAVE 6 DISABLED TESTS
>>
>> make[4]: *** [check-local] Error 1
>> make[4]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
>> make[3]: *** [check-am] Error 2
>> make[3]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
>> make[2]: *** [check] Error 2
>> make[2]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
>> make[1]: Leaving directory `/mesos/mesos-0.29.0/_build'
>> make[1]: *** [check-recursive] Error 1
>> make: *** [distcheck] Error 1
>> + docker rmi mesos-1459230127-13906
>> Untagged: mesos-1459230127-13906:latest
>> Deleted: cacb8f6afe4d7fdbe1ac7bf1ad0338181413f3a81b5b1c5cf56bf2667f810aad
>> Deleted: f48e36119876751f898378fa38c0e9cc8f01f3566284450b1e917b855f4520c5
>> Deleted: 90526e7a88d474bb45f1edb44c7cc3eea0151ecca4be9ec0d3765d1888fa8315
>> Deleted: 76e7df84fa713c1a9786f5f1f4c4dda9be08c32e292c151e01049bb1cbcd448c
>> Deleted: cac845a110e827f30f8bcde5084d1a8b2b252fd7e3593d123763a75d1d9f7af8
>> Deleted: 2304e9792ef33b93a77f035160b9de70501c135bddfe18a6f16023f51faeb60a
>> Deleted: 6eb7b0792e8661dd7e8d1d95eb8549bda73ce4da64e2274240ebf39d73ee0ffb
>> Deleted: cb90fa476408199deb8e11824723b16bc05ca545548b773aa30a49f2295f8f77
>> Deleted: f7d306edca1ab89b22abc8e15bd4bac995e67183c4620cf654c15721598f9c4d
>> Deleted: 76558ca548aa164ffe882d74281b3d03f6be8cc76b5b71b7b6cee7d1e95f9677
>> Deleted: a236590f9a5e81a39c2acf4e88ba0dc7706b44b6093ddb6a37d5809c6e27f3e7
>> Deleted: 6507f80835107a1b45ec2612528378e6ea33acc3ab14e635cd9bb4e6cf217073
>> Deleted: 8fbd0d2550c193ccb5107a9a13540cd1646e18d01286449d09cb9c7184d77186
>> Deleted: bc9b5531d8a17a788ca2cec0731fdc7ef3f3e7d3a0e6c8c10e5e20dbc38a60d5
>> Deleted: b005ae3936a4887eeee6a9ce4d5a41ba3e61b8ea4267ed01e09a3cbdca026684
>> Deleted: 80c1f8ce2529191ea73a5ab2339172c0c801f7e56bd326b240015eae5c680502
>> Build step 'Execute shell' marked build as failure
>>
>
>

Re: Build failed in Jenkins: Mesos » clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1888

Posted by Benjamin Mahler <bm...@apache.org>.
+joseph

On Mon, Mar 28, 2016 at 11:32 PM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:

> See <
> https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1888/changes
> >
>
> Changes:
>
> [yujie.jay] Adapted port_mapping isolator with missing subprocess
> parameter.
>
> [yujie.jay] Fixed typo in subprocess doxygen comments.
>
> ------------------------------------------
> [...truncated 178901 lines...]
> I0329 06:24:26.085312 32305 master.cpp:378] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_http="true"
> --authenticate_slaves="true" --authenticators="crammd5"
> --authorizers="local" --credentials="/tmp/h70a8A/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.29.0/_inst/share/mesos/webui"
> --work_dir="/tmp/h70a8A/master" --zk_session_timeout="10secs"
> I0329 06:24:26.085846 32305 master.cpp:429] Master allowing
> unauthenticated frameworks to register
> I0329 06:24:26.085932 32305 master.cpp:432] Master only allowing
> authenticated slaves to register
> I0329 06:24:26.086014 32305 credentials.hpp:37] Loading credentials for
> authentication from '/tmp/h70a8A/credentials'
> I0329 06:24:26.089220 32305 master.cpp:474] Using default 'crammd5'
> authenticator
> I0329 06:24:26.089391 32305 master.cpp:545] Using default 'basic' HTTP
> authenticator
> I0329 06:24:26.089530 32305 master.cpp:583] Authorization enabled
> I0329 06:24:26.092001 32305 hierarchical.cpp:144] Initialized hierarchical
> allocator process
> I0329 06:24:26.092093 32305 whitelist_watcher.cpp:77] No whitelist given
> I0329 06:24:26.093214 32310 master.cpp:1826] The newly elected leader is
> master@172.17.0.2:44577 with id c0df3402-8c18-4794-aea1-d0fc2093455d
> I0329 06:24:26.093361 32310 master.cpp:1839] Elected as the leading master!
> I0329 06:24:26.093479 32310 master.cpp:1526] Recovering from registrar
> I0329 06:24:26.093793 32302 registrar.cpp:307] Recovering registrar
> I0329 06:24:26.097321 32299 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 28.543546ms
> I0329 06:24:26.097383 32299 replica.cpp:320] Persisted replica status to
> STARTING
> I0329 06:24:26.097652 32299 recover.cpp:473] Replica is in STARTING status
> I0329 06:24:26.099536 32300 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (16412)@172.17.0.2:44577
> I0329 06:24:26.100143 32300 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I0329 06:24:26.100862 32300 recover.cpp:564] Updating replica status to
> VOTING
> I0329 06:24:26.131098 32300 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 30.047383ms
> I0329 06:24:26.131202 32300 replica.cpp:320] Persisted replica status to
> VOTING
> I0329 06:24:26.131471 32300 recover.cpp:578] Successfully joined the Paxos
> group
> I0329 06:24:26.131674 32300 recover.cpp:462] Recover process terminated
> I0329 06:24:26.132522 32300 log.cpp:659] Attempting to start the writer
> I0329 06:24:26.134305 32300 replica.cpp:493] Replica received implicit
> promise request from (16413)@172.17.0.2:44577 with proposal 1
> I0329 06:24:26.164098 32300 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 29.777838ms
> I0329 06:24:26.164202 32300 replica.cpp:342] Persisted promised to 1
> I0329 06:24:26.174093 32307 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0329 06:24:26.177204 32307 replica.cpp:388] Replica received explicit
> promise request from (16414)@172.17.0.2:44577 for position 0 with
> proposal 2
> I0329 06:24:26.202256 32307 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 24.828611ms
> I0329 06:24:26.202365 32307 replica.cpp:712] Persisted action at 0
> I0329 06:24:26.204294 32307 replica.cpp:537] Replica received write
> request for position 0 from (16415)@172.17.0.2:44577
> I0329 06:24:26.204396 32307 leveldb.cpp:436] Reading position from leveldb
> took 63731ns
> I0329 06:24:26.227409 32307 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 22.997586ms
> I0329 06:24:26.227517 32307 replica.cpp:712] Persisted action at 0
> I0329 06:24:26.233665 32299 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0329 06:24:26.252598 32299 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 18.804081ms
> I0329 06:24:26.252712 32299 replica.cpp:712] Persisted action at 0
> I0329 06:24:26.252753 32299 replica.cpp:697] Replica learned NOP action at
> position 0
> I0329 06:24:26.254039 32299 log.cpp:675] Writer started with ending
> position 0
> I0329 06:24:26.255581 32299 leveldb.cpp:436] Reading position from leveldb
> took 100584ns
> I0329 06:24:26.263558 32306 registrar.cpp:340] Successfully fetched the
> registry (0B) in 169.595904ms
> I0329 06:24:26.263902 32306 registrar.cpp:439] Applied 1 operations in
> 60146ns; attempting to update the 'registry'
> I0329 06:24:26.266656 32304 log.cpp:683] Attempting to append 170 bytes to
> the log
> I0329 06:24:26.266984 32304 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0329 06:24:26.268291 32304 replica.cpp:537] Replica received write
> request for position 1 from (16416)@172.17.0.2:44577
> I0329 06:24:26.294421 32304 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 26.109178ms
> I0329 06:24:26.294524 32304 replica.cpp:712] Persisted action at 1
> I0329 06:24:26.296066 32304 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I0329 06:24:26.319604 32304 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 23.539377ms
> I0329 06:24:26.319721 32304 replica.cpp:712] Persisted action at 1
> I0329 06:24:26.319761 32304 replica.cpp:697] Replica learned APPEND action
> at position 1
> I0329 06:24:26.322026 32304 registrar.cpp:484] Successfully updated the
> 'registry' in 58.029056ms
> I0329 06:24:26.322201 32304 registrar.cpp:370] Successfully recovered
> registrar
> I0329 06:24:26.322477 32304 log.cpp:702] Attempting to truncate the log to
> 1
> I0329 06:24:26.323202 32309 master.cpp:1634] Recovered 0 slaves from the
> Registry (131B) ; allowing 10mins for slaves to re-register
> I0329 06:24:26.323429 32309 hierarchical.cpp:171] Skipping recovery of
> hierarchical allocator: nothing to recover
> I0329 06:24:26.323717 32296 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I0329 06:24:26.325094 32296 replica.cpp:537] Replica received write
> request for position 2 from (16417)@172.17.0.2:44577
> I0329 06:24:26.344977 32296 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 19.869767ms
> I0329 06:24:26.345098 32296 replica.cpp:712] Persisted action at 2
> I0329 06:24:26.346740 32296 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I0329 06:24:26.370178 32296 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 23.433093ms
> I0329 06:24:26.370374 32296 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 101580ns
> I0329 06:24:26.370405 32296 replica.cpp:712] Persisted action at 2
> I0329 06:24:26.370445 32296 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I0329 06:24:26.375056 32278 scheduler.cpp:172] Version: 0.29.0
> I0329 06:24:26.376142 32296 scheduler.cpp:438] New master detected at
> master@172.17.0.2:44577
> I0329 06:24:26.378262 32304 scheduler.cpp:338] Connected with the master
> at http://172.17.0.2:44577/master/api/v1/scheduler
> I0329 06:24:26.379989 32298 scheduler.cpp:231] Sending SUBSCRIBE call to
> http://172.17.0.2:44577/master/api/v1/scheduler
> I0329 06:24:26.381155 32304 process.cpp:3136] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0329 06:24:26.381928 32297 http.cpp:313] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.2:60727
> I0329 06:24:26.382292 32297 master.cpp:2088] Received subscription request
> for HTTP framework 'default'
> I0329 06:24:26.382323 32297 master.cpp:1865] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I0329 06:24:26.383196 32297 master.cpp:2179] Subscribing framework
> 'default' with checkpointing disabled and capabilities [  ]
> I0329 06:24:26.384454 32297 hierarchical.cpp:266] Added framework
> c0df3402-8c18-4794-aea1-d0fc2093455d-0000
> I0329 06:24:26.384557 32297 hierarchical.cpp:1490] No resources available
> to allocate!
> I0329 06:24:26.384598 32297 hierarchical.cpp:1585] No inverse offers to
> send out!
> I0329 06:24:26.384667 32297 hierarchical.cpp:1141] Performed allocation
> for 0 slaves in 195737ns
> I0329 06:24:26.384935 32297 master.hpp:1715] Sending heartbeat to
> c0df3402-8c18-4794-aea1-d0fc2093455d-0000
> I0329 06:24:26.385965 32306 scheduler.cpp:629] Enqueuing event SUBSCRIBED
> received from http://172.17.0.2:44577/master/api/v1/scheduler
> I0329 06:24:26.386927 32306 scheduler.cpp:629] Enqueuing event HEARTBEAT
> received from http://172.17.0.2:44577/master/api/v1/scheduler
> I0329 06:24:26.388162 32301 scheduler.cpp:231] Sending REQUEST call to
> http://172.17.0.2:44577/master/api/v1/scheduler
> I0329 06:24:26.389529 32301 process.cpp:3136] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0329 06:24:26.390229 32300 http.cpp:313] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.2:60728
> I0329 06:24:26.390472 32300 master.cpp:2785] Processing REQUEST call for
> framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
> I0329 06:24:26.390704 32303 hierarchical.cpp:592] Received resource
> request from framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000
> I0329 06:24:26.394057 32305 master.cpp:1269] Framework
> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default) disconnected
> I0329 06:24:26.394111 32305 master.cpp:2642] Disconnecting framework
> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
> I0329 06:24:26.394139 32305 master.cpp:2666] Deactivating framework
> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
> I0329 06:24:26.394261 32305 master.cpp:1293] Giving framework
> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default) 0ns to failover
> I0329 06:24:26.394523 32305 hierarchical.cpp:378] Deactivated framework
> c0df3402-8c18-4794-aea1-d0fc2093455d-0000
> I0329 06:24:26.395911 32305 master.cpp:5360] Framework failover timeout,
> removing framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
> I0329 06:24:26.395953 32305 master.cpp:6093] Removing framework
> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default)
> I0329 06:24:26.396348 32305 hierarchical.cpp:329] Removed framework
> c0df3402-8c18-4794-aea1-d0fc2093455d-0000
> I0329 06:24:26.404429 32278 master.cpp:1083] Master terminating
> [       OK ] ContentType/SchedulerTest.Request/0 (666 ms)
> [ RUN      ] ContentType/SchedulerTest.Request/1
> I0329 06:24:26.430703 32278 cluster.cpp:139] Creating default 'local'
> authorizer
> I0329 06:24:26.515738 32278 leveldb.cpp:174] Opened db in 84.58043ms
> I0329 06:24:26.541720 32278 leveldb.cpp:181] Compacted db in 25.941366ms
> I0329 06:24:26.541827 32278 leveldb.cpp:196] Created db iterator in 35061ns
> I0329 06:24:26.541844 32278 leveldb.cpp:202] Seeked to beginning of db in
> 9132ns
> I0329 06:24:26.541856 32278 leveldb.cpp:271] Iterated through 0 keys in
> the db in 6113ns
> I0329 06:24:26.541901 32278 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0329 06:24:26.542896 32309 recover.cpp:447] Starting replica recovery
> I0329 06:24:26.543231 32309 recover.cpp:473] Replica is in EMPTY status
> I0329 06:24:26.544759 32302 replica.cpp:673] Replica in EMPTY status
> received a broadcasted recover request from (16425)@172.17.0.2:44577
> I0329 06:24:26.545496 32309 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I0329 06:24:26.546010 32309 recover.cpp:564] Updating replica status to
> STARTING
> I0329 06:24:26.550267 32300 master.cpp:376] Master
> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c (58e403dcc250) started on
> 172.17.0.2:44577
> I0329 06:24:26.550334 32300 master.cpp:378] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_http="true"
> --authenticate_slaves="true" --authenticators="crammd5"
> --authorizers="local" --credentials="/tmp/rgiyDU/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.29.0/_inst/share/mesos/webui"
> --work_dir="/tmp/rgiyDU/master" --zk_session_timeout="10secs"
> I0329 06:24:26.550730 32300 master.cpp:429] Master allowing
> unauthenticated frameworks to register
> I0329 06:24:26.550742 32300 master.cpp:432] Master only allowing
> authenticated slaves to register
> I0329 06:24:26.550750 32300 credentials.hpp:37] Loading credentials for
> authentication from '/tmp/rgiyDU/credentials'
> I0329 06:24:26.551120 32300 master.cpp:474] Using default 'crammd5'
> authenticator
> I0329 06:24:26.551282 32300 master.cpp:545] Using default 'basic' HTTP
> authenticator
> I0329 06:24:26.551408 32300 master.cpp:583] Authorization enabled
> I0329 06:24:26.553966 32301 whitelist_watcher.cpp:77] No whitelist given
> I0329 06:24:26.554941 32308 master.cpp:1826] The newly elected leader is
> master@172.17.0.2:44577 with id e055dc0c-92ec-4a53-9dad-b00b45fc8a0c
> I0329 06:24:26.555119 32308 master.cpp:1839] Elected as the leading master!
> I0329 06:24:26.555203 32308 master.cpp:1526] Recovering from registrar
> I0329 06:24:26.555528 32308 registrar.cpp:307] Recovering registrar
> I0329 06:24:26.556252 32300 hierarchical.cpp:144] Initialized hierarchical
> allocator process
> I0329 06:24:26.583319 32309 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 37.070461ms
> I0329 06:24:26.583428 32309 replica.cpp:320] Persisted replica status to
> STARTING
> I0329 06:24:26.583833 32300 recover.cpp:473] Replica is in STARTING status
> I0329 06:24:26.585232 32307 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (16427)@172.17.0.2:44577
> I0329 06:24:26.585587 32299 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I0329 06:24:26.586372 32307 recover.cpp:564] Updating replica status to
> VOTING
> I0329 06:24:26.625116 32298 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 38.529877ms
> I0329 06:24:26.625231 32298 replica.cpp:320] Persisted replica status to
> VOTING
> I0329 06:24:26.625517 32303 recover.cpp:578] Successfully joined the Paxos
> group
> I0329 06:24:26.625844 32303 recover.cpp:462] Recover process terminated
> I0329 06:24:26.626432 32311 log.cpp:659] Attempting to start the writer
> I0329 06:24:26.627914 32309 replica.cpp:493] Replica received implicit
> promise request from (16428)@172.17.0.2:44577 with proposal 1
> I0329 06:24:26.658563 32309 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 30.632068ms
> I0329 06:24:26.658671 32309 replica.cpp:342] Persisted promised to 1
> I0329 06:24:26.659965 32299 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0329 06:24:26.661671 32307 replica.cpp:388] Replica received explicit
> promise request from (16429)@172.17.0.2:44577 for position 0 with
> proposal 2
> I0329 06:24:26.700374 32307 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 38.66346ms
> I0329 06:24:26.700486 32307 replica.cpp:712] Persisted action at 0
> I0329 06:24:26.702354 32301 replica.cpp:537] Replica received write
> request for position 0 from (16430)@172.17.0.2:44577
> I0329 06:24:26.702471 32301 leveldb.cpp:436] Reading position from leveldb
> took 60629ns
> I0329 06:24:26.733788 32301 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 31.29248ms
> I0329 06:24:26.733875 32301 replica.cpp:712] Persisted action at 0
> I0329 06:24:26.734787 32307 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0329 06:24:26.766201 32307 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 31.401125ms
> I0329 06:24:26.766286 32307 replica.cpp:712] Persisted action at 0
> I0329 06:24:26.766320 32307 replica.cpp:697] Replica learned NOP action at
> position 0
> I0329 06:24:26.767251 32301 log.cpp:675] Writer started with ending
> position 0
> I0329 06:24:26.768996 32298 leveldb.cpp:436] Reading position from leveldb
> took 111416ns
> I0329 06:24:26.770465 32298 registrar.cpp:340] Successfully fetched the
> registry (0B) in 214.683136ms
> I0329 06:24:26.770622 32298 registrar.cpp:439] Applied 1 operations in
> 54730ns; attempting to update the 'registry'
> I0329 06:24:26.772197 32298 log.cpp:683] Attempting to append 170 bytes to
> the log
> I0329 06:24:26.772478 32299 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0329 06:24:26.773361 32302 replica.cpp:537] Replica received write
> request for position 1 from (16431)@172.17.0.2:44577
> I0329 06:24:26.812733 32302 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 39.346476ms
> I0329 06:24:26.812857 32302 replica.cpp:712] Persisted action at 1
> I0329 06:24:26.813958 32299 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I0329 06:24:26.837826 32299 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 23.866792ms
> I0329 06:24:26.837930 32299 replica.cpp:712] Persisted action at 1
> I0329 06:24:26.837967 32299 replica.cpp:697] Replica learned APPEND action
> at position 1
> I0329 06:24:26.839619 32311 registrar.cpp:484] Successfully updated the
> 'registry' in 68.916992ms
> I0329 06:24:26.839851 32311 registrar.cpp:370] Successfully recovered
> registrar
> I0329 06:24:26.839921 32300 log.cpp:702] Attempting to truncate the log to
> 1
> I0329 06:24:26.840009 32299 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I0329 06:24:26.840361 32300 master.cpp:1634] Recovered 0 slaves from the
> Registry (131B) ; allowing 10mins for slaves to re-register
> I0329 06:24:26.840445 32305 hierarchical.cpp:171] Skipping recovery of
> hierarchical allocator: nothing to recover
> I0329 06:24:26.841119 32306 replica.cpp:537] Replica received write
> request for position 2 from (16432)@172.17.0.2:44577
> I0329 06:24:26.863555 32306 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 22.418343ms
> I0329 06:24:26.863641 32306 replica.cpp:712] Persisted action at 2
> I0329 06:24:26.865054 32306 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I0329 06:24:26.883360 32306 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 18.305162ms
> I0329 06:24:26.883512 32306 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 83579ns
> I0329 06:24:26.883538 32306 replica.cpp:712] Persisted action at 2
> I0329 06:24:26.883570 32306 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I0329 06:24:26.891973 32278 scheduler.cpp:172] Version: 0.29.0
> I0329 06:24:26.892935 32302 scheduler.cpp:438] New master detected at
> master@172.17.0.2:44577
> I0329 06:24:26.894947 32298 scheduler.cpp:338] Connected with the master
> at http://172.17.0.2:44577/master/api/v1/scheduler
> I0329 06:24:26.896307 32309 scheduler.cpp:231] Sending SUBSCRIBE call to
> http://172.17.0.2:44577/master/api/v1/scheduler
> I0329 06:24:26.897629 32309 process.cpp:3136] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0329 06:24:26.898205 32309 http.cpp:313] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.2:60732
> I0329 06:24:26.898597 32309 master.cpp:2088] Received subscription request
> for HTTP framework 'default'
> I0329 06:24:26.898622 32309 master.cpp:1865] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I0329 06:24:26.899363 32309 master.cpp:2179] Subscribing framework
> 'default' with checkpointing disabled and capabilities [  ]
> I0329 06:24:26.900554 32309 hierarchical.cpp:266] Added framework
> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
> I0329 06:24:26.900652 32309 hierarchical.cpp:1490] No resources available
> to allocate!
> I0329 06:24:26.900890 32303 master.hpp:1715] Sending heartbeat to
> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
> I0329 06:24:26.902096 32304 scheduler.cpp:629] Enqueuing event SUBSCRIBED
> received from http://172.17.0.2:44577/master/api/v1/scheduler
> I0329 06:24:26.902498 32304 scheduler.cpp:629] Enqueuing event HEARTBEAT
> received from http://172.17.0.2:44577/master/api/v1/scheduler
> I0329 06:24:26.902828 32309 hierarchical.cpp:1585] No inverse offers to
> send out!
> I0329 06:24:26.902901 32309 hierarchical.cpp:1141] Performed allocation
> for 0 slaves in 2.330913ms
> I0329 06:24:26.903455 32308 scheduler.cpp:231] Sending REQUEST call to
> http://172.17.0.2:44577/master/api/v1/scheduler
> I0329 06:24:26.904709 32302 process.cpp:3136] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0329 06:24:26.905304 32302 http.cpp:313] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.2:60733
> I0329 06:24:26.905647 32302 master.cpp:2785] Processing REQUEST call for
> framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
> I0329 06:24:26.905892 32302 hierarchical.cpp:592] Received resource
> request from framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
> I0329 06:24:26.908712 32304 master.cpp:1269] Framework
> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default) disconnected
> I0329 06:24:26.908759 32304 master.cpp:2642] Disconnecting framework
> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
> I0329 06:24:26.908783 32304 master.cpp:2666] Deactivating framework
> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
> I0329 06:24:26.908882 32304 master.cpp:1293] Giving framework
> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default) 0ns to failover
> I0329 06:24:26.909106 32304 hierarchical.cpp:378] Deactivated framework
> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
> I0329 06:24:26.910423 32304 master.cpp:5360] Framework failover timeout,
> removing framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
> I0329 06:24:26.910450 32304 master.cpp:6093] Removing framework
> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default)
> I0329 06:24:26.910758 32304 hierarchical.cpp:329] Removed framework
> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000
> I0329 06:24:26.919173 32278 master.cpp:1083] Master terminating
> [       OK ] ContentType/SchedulerTest.Request/1 (499 ms)
> [----------] 26 tests from ContentType/SchedulerTest (19532 ms total)
>
> [----------] Global test environment tear-down
> E0329 06:24:27.207061 32278 environment.cpp:778] Failed to remove
> '/tmp/LogZooKeeperTest_WriteRead_isx6ON': No such file or directory
> E0329 06:24:27.207288 32278 environment.cpp:778] Failed to remove
> '/tmp/LogZooKeeperTest_LostZooKeeper_UHzb62': No such file or directory
> [==========] 1052 tests from 132 test cases ran. (788517 ms total)
> [  PASSED  ] 1051 tests.
> [  FAILED  ] 1 test, listed below:
> [  FAILED  ] ContainerLoggerTest.LOGROTATE_RotateInSandbox
>
>  1 FAILED TEST
>   YOU HAVE 6 DISABLED TESTS
>
> make[4]: *** [check-local] Error 1
> make[4]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> make[3]: *** [check-am] Error 2
> make[3]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> make[2]: *** [check] Error 2
> make[2]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> make[1]: Leaving directory `/mesos/mesos-0.29.0/_build'
> make[1]: *** [check-recursive] Error 1
> make: *** [distcheck] Error 1
> + docker rmi mesos-1459230127-13906
> Untagged: mesos-1459230127-13906:latest
> Deleted: cacb8f6afe4d7fdbe1ac7bf1ad0338181413f3a81b5b1c5cf56bf2667f810aad
> Deleted: f48e36119876751f898378fa38c0e9cc8f01f3566284450b1e917b855f4520c5
> Deleted: 90526e7a88d474bb45f1edb44c7cc3eea0151ecca4be9ec0d3765d1888fa8315
> Deleted: 76e7df84fa713c1a9786f5f1f4c4dda9be08c32e292c151e01049bb1cbcd448c
> Deleted: cac845a110e827f30f8bcde5084d1a8b2b252fd7e3593d123763a75d1d9f7af8
> Deleted: 2304e9792ef33b93a77f035160b9de70501c135bddfe18a6f16023f51faeb60a
> Deleted: 6eb7b0792e8661dd7e8d1d95eb8549bda73ce4da64e2274240ebf39d73ee0ffb
> Deleted: cb90fa476408199deb8e11824723b16bc05ca545548b773aa30a49f2295f8f77
> Deleted: f7d306edca1ab89b22abc8e15bd4bac995e67183c4620cf654c15721598f9c4d
> Deleted: 76558ca548aa164ffe882d74281b3d03f6be8cc76b5b71b7b6cee7d1e95f9677
> Deleted: a236590f9a5e81a39c2acf4e88ba0dc7706b44b6093ddb6a37d5809c6e27f3e7
> Deleted: 6507f80835107a1b45ec2612528378e6ea33acc3ab14e635cd9bb4e6cf217073
> Deleted: 8fbd0d2550c193ccb5107a9a13540cd1646e18d01286449d09cb9c7184d77186
> Deleted: bc9b5531d8a17a788ca2cec0731fdc7ef3f3e7d3a0e6c8c10e5e20dbc38a60d5
> Deleted: b005ae3936a4887eeee6a9ce4d5a41ba3e61b8ea4267ed01e09a3cbdca026684
> Deleted: 80c1f8ce2529191ea73a5ab2339172c0c801f7e56bd326b240015eae5c680502
> Build step 'Execute shell' marked build as failure
>

Jenkins build is back to normal : Mesos » clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1889

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1889/>