You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2016/02/08 19:55:03 UTC
Build failed in Jenkins: Mesos » clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,docker||Hadoop #1638
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/1638/changes>
Changes:
[tnachen] Fixed volume paths for command tasks with image.
------------------------------------------
[...truncated 168892 lines...]
I0208 18:54:59.507760 30915 gc.cpp:54] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_VAUlwy/slaves/f6287b34-e9e5-49c6-944c-207949c766df-S0/frameworks/f6287b34-e9e5-49c6-944c-207949c766df-0000' for gc 6.99999412415407days in the future
[ OK ] ContentType/SchedulerTest.Message/1 (732 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I0208 18:54:59.628785 30884 leveldb.cpp:174] Opened db in 103.496715ms
I0208 18:54:59.670656 30884 leveldb.cpp:181] Compacted db in 41.820358ms
I0208 18:54:59.670758 30884 leveldb.cpp:196] Created db iterator in 35679ns
I0208 18:54:59.670776 30884 leveldb.cpp:202] Seeked to beginning of db in 9294ns
I0208 18:54:59.670789 30884 leveldb.cpp:271] Iterated through 0 keys in the db in 6672ns
I0208 18:54:59.670842 30884 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0208 18:54:59.671847 30915 recover.cpp:447] Starting replica recovery
I0208 18:54:59.672268 30908 recover.cpp:473] Replica is in EMPTY status
I0208 18:54:59.673606 30915 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (14360)@172.17.0.3:46699
I0208 18:54:59.674569 30908 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0208 18:54:59.675142 30908 recover.cpp:564] Updating replica status to STARTING
I0208 18:54:59.692708 30917 master.cpp:376] Master 57643a42-47d1-44e2-bb42-d7b37fd29367 (861577ef7168) started on 172.17.0.3:46699
I0208 18:54:59.692939 30917 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/mPfHX7/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.28.0/_inst/share/mesos/webui" --work_dir="/tmp/mPfHX7/master" --zk_session_timeout="10secs"
I0208 18:54:59.693451 30917 master.cpp:425] Master allowing unauthenticated frameworks to register
I0208 18:54:59.693516 30917 master.cpp:428] Master only allowing authenticated slaves to register
I0208 18:54:59.693575 30917 credentials.hpp:35] Loading credentials for authentication from '/tmp/mPfHX7/credentials'
I0208 18:54:59.693987 30917 master.cpp:468] Using default 'crammd5' authenticator
I0208 18:54:59.694466 30917 master.cpp:537] Using default 'basic' HTTP authenticator
I0208 18:54:59.694761 30917 master.cpp:571] Authorization enabled
I0208 18:54:59.695298 30915 hierarchical.cpp:144] Initialized hierarchical allocator process
I0208 18:54:59.695380 30912 whitelist_watcher.cpp:77] No whitelist given
I0208 18:54:59.699028 30917 master.cpp:1712] The newly elected leader is master@172.17.0.3:46699 with id 57643a42-47d1-44e2-bb42-d7b37fd29367
I0208 18:54:59.699097 30917 master.cpp:1725] Elected as the leading master!
I0208 18:54:59.699111 30917 master.cpp:1470] Recovering from registrar
I0208 18:54:59.700340 30909 registrar.cpp:307] Recovering registrar
I0208 18:54:59.703752 30908 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 28.190103ms
I0208 18:54:59.703831 30908 replica.cpp:320] Persisted replica status to STARTING
I0208 18:54:59.704115 30908 recover.cpp:473] Replica is in STARTING status
I0208 18:54:59.705976 30908 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (14363)@172.17.0.3:46699
I0208 18:54:59.706395 30908 recover.cpp:193] Received a recover response from a replica in STARTING status
I0208 18:54:59.707114 30908 recover.cpp:564] Updating replica status to VOTING
I0208 18:54:59.737870 30909 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.473061ms
I0208 18:54:59.737967 30909 replica.cpp:320] Persisted replica status to VOTING
I0208 18:54:59.738236 30909 recover.cpp:578] Successfully joined the Paxos group
I0208 18:54:59.738418 30909 recover.cpp:462] Recover process terminated
I0208 18:54:59.739172 30909 log.cpp:659] Attempting to start the writer
I0208 18:54:59.740962 30909 replica.cpp:493] Replica received implicit promise request from (14364)@172.17.0.3:46699 with proposal 1
I0208 18:54:59.771332 30909 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.288877ms
I0208 18:54:59.771428 30909 replica.cpp:342] Persisted promised to 1
I0208 18:54:59.772766 30909 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0208 18:54:59.774515 30915 replica.cpp:388] Replica received explicit promise request from (14365)@172.17.0.3:46699 for position 0 with proposal 2
I0208 18:54:59.804795 30915 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 30.198609ms
I0208 18:54:59.804893 30915 replica.cpp:712] Persisted action at 0
I0208 18:54:59.806735 30915 replica.cpp:537] Replica received write request for position 0 from (14366)@172.17.0.3:46699
I0208 18:54:59.806819 30915 leveldb.cpp:436] Reading position from leveldb took 58582ns
I0208 18:54:59.833856 30915 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 27.019885ms
I0208 18:54:59.833957 30915 replica.cpp:712] Persisted action at 0
I0208 18:54:59.841922 30902 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0208 18:54:59.867244 30902 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 25.248587ms
I0208 18:54:59.867300 30902 replica.cpp:712] Persisted action at 0
I0208 18:54:59.867326 30902 replica.cpp:697] Replica learned NOP action at position 0
I0208 18:54:59.868388 30902 log.cpp:675] Writer started with ending position 0
I0208 18:54:59.869875 30902 leveldb.cpp:436] Reading position from leveldb took 77691ns
I0208 18:54:59.871314 30902 registrar.cpp:340] Successfully fetched the registry (0B) in 170.928128ms
I0208 18:54:59.871474 30902 registrar.cpp:439] Applied 1 operations in 53910ns; attempting to update the 'registry'
I0208 18:54:59.872838 30902 log.cpp:683] Attempting to append 170 bytes to the log
I0208 18:54:59.873090 30902 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0208 18:54:59.874408 30902 replica.cpp:537] Replica received write request for position 1 from (14367)@172.17.0.3:46699
I0208 18:54:59.900756 30902 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 26.326342ms
I0208 18:54:59.901283 30902 replica.cpp:712] Persisted action at 1
I0208 18:54:59.911499 30911 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0208 18:54:59.934198 30911 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 21.941942ms
I0208 18:54:59.934312 30911 replica.cpp:712] Persisted action at 1
I0208 18:54:59.934350 30911 replica.cpp:697] Replica learned APPEND action at position 1
I0208 18:54:59.936369 30903 log.cpp:702] Attempting to truncate the log to 1
I0208 18:54:59.936554 30905 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0208 18:54:59.936375 30913 registrar.cpp:484] Successfully updated the 'registry' in 64.78208ms
I0208 18:54:59.937690 30903 replica.cpp:537] Replica received write request for position 2 from (14368)@172.17.0.3:46699
I0208 18:54:59.937969 30913 registrar.cpp:370] Successfully recovered registrar
I0208 18:54:59.938452 30913 master.cpp:1522] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0208 18:54:59.938691 30913 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0208 18:54:59.959329 30903 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 21.618776ms
I0208 18:54:59.959431 30903 replica.cpp:712] Persisted action at 2
I0208 18:54:59.960988 30903 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0208 18:54:59.984447 30903 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 23.45595ms
I0208 18:54:59.984632 30903 leveldb.cpp:399] Deleting ~1 keys from leveldb took 100011ns
I0208 18:54:59.984661 30903 replica.cpp:712] Persisted action at 2
I0208 18:54:59.984697 30903 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0208 18:54:59.993151 30884 scheduler.cpp:154] Version: 0.28.0
I0208 18:54:59.994057 30904 scheduler.cpp:236] New master detected at master@172.17.0.3:46699
I0208 18:54:59.995599 30904 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.3:46699
I0208 18:54:59.998322 30916 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0208 18:54:59.998875 30916 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:51942
I0208 18:54:59.999104 30916 master.cpp:1974] Received subscription request for HTTP framework 'default'
I0208 18:54:59.999125 30916 master.cpp:1751] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0208 18:54:59.999516 30916 master.cpp:2065] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0208 18:55:00.000095 30916 hierarchical.cpp:265] Added framework 57643a42-47d1-44e2-bb42-d7b37fd29367-0000
I0208 18:55:00.000185 30916 hierarchical.cpp:1403] No resources available to allocate!
I0208 18:55:00.000264 30916 hierarchical.cpp:1498] No inverse offers to send out!
I0208 18:55:00.000457 30916 hierarchical.cpp:1096] Performed allocation for 0 slaves in 353186ns
I0208 18:55:00.000267 30914 master.hpp:1658] Sending heartbeat to 57643a42-47d1-44e2-bb42-d7b37fd29367-0000
I0208 18:55:00.002504 30910 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.3:46699
I0208 18:55:00.003248 30910 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.3:46699
I0208 18:55:00.004165 30910 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0208 18:55:00.005381 30910 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.3:46699
I0208 18:55:00.007500 30917 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0208 18:55:00.007988 30903 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:51943
I0208 18:55:00.008118 30903 master.cpp:2719] Processing REQUEST call for framework 57643a42-47d1-44e2-bb42-d7b37fd29367-0000 (default)
I0208 18:55:00.008396 30903 hierarchical.cpp:589] Received resource request from framework 57643a42-47d1-44e2-bb42-d7b37fd29367-0000
I0208 18:55:00.009335 30884 master.cpp:1027] Master terminating
I0208 18:55:00.010391 30915 hierarchical.cpp:326] Removed framework 57643a42-47d1-44e2-bb42-d7b37fd29367-0000
E0208 18:55:00.012096 30907 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (504 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I0208 18:55:00.109006 30884 leveldb.cpp:174] Opened db in 79.961824ms
I0208 18:55:00.159271 30884 leveldb.cpp:181] Compacted db in 49.719181ms
I0208 18:55:00.159381 30884 leveldb.cpp:196] Created db iterator in 41476ns
I0208 18:55:00.159406 30884 leveldb.cpp:202] Seeked to beginning of db in 11971ns
I0208 18:55:00.159421 30884 leveldb.cpp:271] Iterated through 0 keys in the db in 8477ns
I0208 18:55:00.159482 30884 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0208 18:55:00.160254 30912 recover.cpp:447] Starting replica recovery
I0208 18:55:00.161846 30914 recover.cpp:473] Replica is in EMPTY status
I0208 18:55:00.163642 30914 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (14376)@172.17.0.3:46699
I0208 18:55:00.164237 30915 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0208 18:55:00.164901 30915 recover.cpp:564] Updating replica status to STARTING
I0208 18:55:00.166333 30904 master.cpp:376] Master 806c1fcb-16a0-4766-b8e0-2b9ea9cda543 (861577ef7168) started on 172.17.0.3:46699
I0208 18:55:00.166369 30904 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/ha6nll/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.28.0/_inst/share/mesos/webui" --work_dir="/tmp/ha6nll/master" --zk_session_timeout="10secs"
I0208 18:55:00.166748 30904 master.cpp:425] Master allowing unauthenticated frameworks to register
I0208 18:55:00.166760 30904 master.cpp:428] Master only allowing authenticated slaves to register
I0208 18:55:00.166769 30904 credentials.hpp:35] Loading credentials for authentication from '/tmp/ha6nll/credentials'
I0208 18:55:00.167142 30904 master.cpp:468] Using default 'crammd5' authenticator
I0208 18:55:00.167593 30904 master.cpp:537] Using default 'basic' HTTP authenticator
I0208 18:55:00.167901 30904 master.cpp:571] Authorization enabled
I0208 18:55:00.168329 30916 hierarchical.cpp:144] Initialized hierarchical allocator process
I0208 18:55:00.168401 30916 whitelist_watcher.cpp:77] No whitelist given
I0208 18:55:00.172530 30910 master.cpp:1712] The newly elected leader is master@172.17.0.3:46699 with id 806c1fcb-16a0-4766-b8e0-2b9ea9cda543
I0208 18:55:00.172590 30910 master.cpp:1725] Elected as the leading master!
I0208 18:55:00.172605 30910 master.cpp:1470] Recovering from registrar
I0208 18:55:00.172871 30910 registrar.cpp:307] Recovering registrar
I0208 18:55:00.209309 30915 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 44.202155ms
I0208 18:55:00.209413 30915 replica.cpp:320] Persisted replica status to STARTING
I0208 18:55:00.209852 30913 recover.cpp:473] Replica is in STARTING status
I0208 18:55:00.211233 30904 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (14378)@172.17.0.3:46699
I0208 18:55:00.211670 30915 recover.cpp:193] Received a recover response from a replica in STARTING status
I0208 18:55:00.212086 30916 recover.cpp:564] Updating replica status to VOTING
I0208 18:55:00.243320 30914 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 31.109594ms
I0208 18:55:00.243422 30914 replica.cpp:320] Persisted replica status to VOTING
I0208 18:55:00.243700 30914 recover.cpp:578] Successfully joined the Paxos group
I0208 18:55:00.243897 30914 recover.cpp:462] Recover process terminated
I0208 18:55:00.244657 30904 log.cpp:659] Attempting to start the writer
I0208 18:55:00.246135 30914 replica.cpp:493] Replica received implicit promise request from (14379)@172.17.0.3:46699 with proposal 1
I0208 18:55:00.276316 30914 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.167749ms
I0208 18:55:00.276418 30914 replica.cpp:342] Persisted promised to 1
I0208 18:55:00.277819 30916 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0208 18:55:00.279662 30916 replica.cpp:388] Replica received explicit promise request from (14380)@172.17.0.3:46699 for position 0 with proposal 2
I0208 18:55:00.310320 30916 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 30.62787ms
I0208 18:55:00.310422 30916 replica.cpp:712] Persisted action at 0
I0208 18:55:00.312407 30915 replica.cpp:537] Replica received write request for position 0 from (14381)@172.17.0.3:46699
I0208 18:55:00.312510 30915 leveldb.cpp:436] Reading position from leveldb took 61545ns
I0208 18:55:00.343324 30915 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 30.794678ms
I0208 18:55:00.343430 30915 replica.cpp:712] Persisted action at 0
I0208 18:55:00.344425 30907 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0208 18:55:00.376541 30907 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 32.111427ms
I0208 18:55:00.376642 30907 replica.cpp:712] Persisted action at 0
I0208 18:55:00.376679 30907 replica.cpp:697] Replica learned NOP action at position 0
I0208 18:55:00.377739 30915 log.cpp:675] Writer started with ending position 0
I0208 18:55:00.379324 30915 leveldb.cpp:436] Reading position from leveldb took 80488ns
I0208 18:55:00.380916 30915 registrar.cpp:340] Successfully fetched the registry (0B) in 208.007168ms
I0208 18:55:00.381503 30915 registrar.cpp:439] Applied 1 operations in 144342ns; attempting to update the 'registry'
I0208 18:55:00.382750 30913 log.cpp:683] Attempting to append 170 bytes to the log
I0208 18:55:00.383303 30905 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0208 18:55:00.384769 30916 replica.cpp:537] Replica received write request for position 1 from (14382)@172.17.0.3:46699
I0208 18:55:00.418339 30916 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 33.479734ms
I0208 18:55:00.418572 30916 replica.cpp:712] Persisted action at 1
I0208 18:55:00.420567 30908 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0208 18:55:00.452318 30908 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 31.57526ms
I0208 18:55:00.452425 30908 replica.cpp:712] Persisted action at 1
I0208 18:55:00.452467 30908 replica.cpp:697] Replica learned APPEND action at position 1
I0208 18:55:00.454103 30914 registrar.cpp:484] Successfully updated the 'registry' in 72.360192ms
I0208 18:55:00.454267 30914 registrar.cpp:370] Successfully recovered registrar
I0208 18:55:00.454449 30905 log.cpp:702] Attempting to truncate the log to 1
I0208 18:55:00.454635 30905 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0208 18:55:00.454731 30914 master.cpp:1522] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0208 18:55:00.454953 30914 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0208 18:55:00.455977 30905 replica.cpp:537] Replica received write request for position 2 from (14383)@172.17.0.3:46699
I0208 18:55:00.480638 30905 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 24.649183ms
I0208 18:55:00.480747 30905 replica.cpp:712] Persisted action at 2
I0208 18:55:00.491260 30913 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0208 18:55:00.514071 30913 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 22.778615ms
I0208 18:55:00.514407 30913 leveldb.cpp:399] Deleting ~1 keys from leveldb took 101055ns
I0208 18:55:00.514468 30913 replica.cpp:712] Persisted action at 2
I0208 18:55:00.514511 30913 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0208 18:55:00.523013 30884 scheduler.cpp:154] Version: 0.28.0
I0208 18:55:00.524111 30903 scheduler.cpp:236] New master detected at master@172.17.0.3:46699
I0208 18:55:00.527071 30913 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.3:46699
I0208 18:55:00.529314 30913 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0208 18:55:00.529911 30913 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:51944
I0208 18:55:00.530346 30913 master.cpp:1974] Received subscription request for HTTP framework 'default'
I0208 18:55:00.530375 30913 master.cpp:1751] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0208 18:55:00.530720 30913 master.cpp:2065] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0208 18:55:00.531250 30905 hierarchical.cpp:265] Added framework 806c1fcb-16a0-4766-b8e0-2b9ea9cda543-0000
I0208 18:55:00.531345 30905 hierarchical.cpp:1403] No resources available to allocate!
I0208 18:55:00.531391 30905 hierarchical.cpp:1498] No inverse offers to send out!
I0208 18:55:00.531426 30905 hierarchical.cpp:1096] Performed allocation for 0 slaves in 161572ns
I0208 18:55:00.531466 30905 master.hpp:1658] Sending heartbeat to 806c1fcb-16a0-4766-b8e0-2b9ea9cda543-0000
I0208 18:55:00.532604 30910 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.3:46699
I0208 18:55:00.533082 30910 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.3:46699
I0208 18:55:00.534056 30902 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0208 18:55:00.534090 30910 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.3:46699
I0208 18:55:00.536380 30905 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0208 18:55:00.537015 30905 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:51945
I0208 18:55:00.537401 30905 master.cpp:2719] Processing REQUEST call for framework 806c1fcb-16a0-4766-b8e0-2b9ea9cda543-0000 (default)
I0208 18:55:00.537688 30905 hierarchical.cpp:589] Received resource request from framework 806c1fcb-16a0-4766-b8e0-2b9ea9cda543-0000
I0208 18:55:00.539427 30909 master.cpp:1027] Master terminating
I0208 18:55:00.539646 30905 hierarchical.cpp:326] Removed framework 806c1fcb-16a0-4766-b8e0-2b9ea9cda543-0000
E0208 18:55:00.540665 30909 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (524 ms)
[----------] 22 tests from ContentType/SchedulerTest (16580 ms total)
[----------] Global test environment tear-down
[==========] 968 tests from 126 test cases ran. (789114 ms total)
[ PASSED ] 967 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] SlaveRecoveryTest/0.CleanupHTTPExecutor, where TypeParam = mesos::internal::slave::MesosContainerizer
1 FAILED TEST
YOU HAVE 8 DISABLED TESTS
make[4]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[4]: *** [check-local] Error 1
make[3]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[3]: *** [check-am] Error 2
make[2]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[2]: *** [check] Error 2
make[1]: Leaving directory `/mesos/mesos-0.28.0/_build'
make[1]: *** [check-recursive] Error 1
make: *** [distcheck] Error 1
+ docker rmi mesos-1454954742-22642
Untagged: mesos-1454954742-22642:latest
Deleted: 0d9e0d923493d0fc07faff27c595dcefa28a886e2cd3b68aadb56e24de769437
Deleted: ec735037b6048f2f9025c631e8938c4923631d2ec48eef03d3d8b4d3117e2253
Deleted: d2fa3526079cfa6aaaf804fbfd97958b172e390844204b61e08b7817a9797def
Deleted: 2dfd8d20e96b043674465d3a2fa6e01d68594e81f5c6edb8a599f1861acf4b61
Deleted: 59d0f677f9cd3f7119b25b1a1f99dd2adefe9f7c4b012fa0d30dfa565a097028
Deleted: 16d34e4775e4cbbd800da402ab730edeefe1a8f050b02c25984620b986f65edb
Deleted: dd8b9e0ef0a3456d3069658758694021673247e3a6cf60c2b8ee0aef66eee124
Deleted: 3202e19891c1f4c1b84b26abb2a0f68d2ce0aab479c7f2ce50c5e73cb9d1a02f
Deleted: 83e8c3fe9d0889f5872352277e762829ce98ca4cb34dcd67828a5347de77d58b
Deleted: f18ffa77fb65aa841bb89f83aa07230c3fe1375b4fb47e7e7bbdfadfea4fa9a0
Deleted: c462c96f02f531a53321d17070905c0397011df1c665147ca862c970a7ba879f
Deleted: ee4f8495dfe7410d3a0d1d0296de9d40c0e71c8271157f4475c77bac969beabb
Deleted: 3f010db7aa066c9261b8bee900bc5b94c214a2b9acf91109260d5cae05c0488d
Deleted: 39d3825bba0616f4bf44022b26059f88333237ab3a02acccb4986bcc474d21dd
Deleted: a8b03faf767250f483ad43e4e13ed3e6aaa5bf07969cc7c3dc7592a518f5e6ac
Deleted: 43493d4650ea40244a34af781dc619caeb843efb5e922c614d461be127cb0ccb
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 #1638
Posted by Greg Mann <gr...@mesosphere.io>.
Anand has a patch up for this one: https://reviews.apache.org/r/43285/
On Mon, Feb 8, 2016 at 10:55 AM, 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/1638/changes
> >
>
> Changes:
>
> [tnachen] Fixed volume paths for command tasks with image.
>
> ------------------------------------------
> [...truncated 168892 lines...]
> I0208 18:54:59.507760 30915 gc.cpp:54] Scheduling
> '/tmp/ContentType_SchedulerTest_Message_1_VAUlwy/slaves/f6287b34-e9e5-49c6-944c-207949c766df-S0/frameworks/f6287b34-e9e5-49c6-944c-207949c766df-0000'
> for gc 6.99999412415407days in the future
> [ OK ] ContentType/SchedulerTest.Message/1 (732 ms)
> [ RUN ] ContentType/SchedulerTest.Request/0
> I0208 18:54:59.628785 30884 leveldb.cpp:174] Opened db in 103.496715ms
> I0208 18:54:59.670656 30884 leveldb.cpp:181] Compacted db in 41.820358ms
> I0208 18:54:59.670758 30884 leveldb.cpp:196] Created db iterator in 35679ns
> I0208 18:54:59.670776 30884 leveldb.cpp:202] Seeked to beginning of db in
> 9294ns
> I0208 18:54:59.670789 30884 leveldb.cpp:271] Iterated through 0 keys in
> the db in 6672ns
> I0208 18:54:59.670842 30884 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0208 18:54:59.671847 30915 recover.cpp:447] Starting replica recovery
> I0208 18:54:59.672268 30908 recover.cpp:473] Replica is in EMPTY status
> I0208 18:54:59.673606 30915 replica.cpp:673] Replica in EMPTY status
> received a broadcasted recover request from (14360)@172.17.0.3:46699
> I0208 18:54:59.674569 30908 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I0208 18:54:59.675142 30908 recover.cpp:564] Updating replica status to
> STARTING
> I0208 18:54:59.692708 30917 master.cpp:376] Master
> 57643a42-47d1-44e2-bb42-d7b37fd29367 (861577ef7168) started on
> 172.17.0.3:46699
> I0208 18:54:59.692939 30917 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/mPfHX7/credentials"
> --framework_sorter="drf" --help="false" --hostname_lookup="true"
> --http_authenticators="basic" --initialize_driver_logging="true"
> --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO"
> --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000"
> --max_slave_ping_timeouts="5" --quiet="false"
> --recovery_slave_removal_limit="100%" --registry="replicated_log"
> --registry_fetch_timeout="1mins" --registry_store_timeout="100secs"
> --registry_strict="true" --root_submissions="true"
> --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins"
> --user_sorter="drf" --version="false"
> --webui_dir="/mesos/mesos-0.28.0/_inst/share/mesos/webui"
> --work_dir="/tmp/mPfHX7/master" --zk_session_timeout="10secs"
> I0208 18:54:59.693451 30917 master.cpp:425] Master allowing
> unauthenticated frameworks to register
> I0208 18:54:59.693516 30917 master.cpp:428] Master only allowing
> authenticated slaves to register
> I0208 18:54:59.693575 30917 credentials.hpp:35] Loading credentials for
> authentication from '/tmp/mPfHX7/credentials'
> I0208 18:54:59.693987 30917 master.cpp:468] Using default 'crammd5'
> authenticator
> I0208 18:54:59.694466 30917 master.cpp:537] Using default 'basic' HTTP
> authenticator
> I0208 18:54:59.694761 30917 master.cpp:571] Authorization enabled
> I0208 18:54:59.695298 30915 hierarchical.cpp:144] Initialized hierarchical
> allocator process
> I0208 18:54:59.695380 30912 whitelist_watcher.cpp:77] No whitelist given
> I0208 18:54:59.699028 30917 master.cpp:1712] The newly elected leader is
> master@172.17.0.3:46699 with id 57643a42-47d1-44e2-bb42-d7b37fd29367
> I0208 18:54:59.699097 30917 master.cpp:1725] Elected as the leading master!
> I0208 18:54:59.699111 30917 master.cpp:1470] Recovering from registrar
> I0208 18:54:59.700340 30909 registrar.cpp:307] Recovering registrar
> I0208 18:54:59.703752 30908 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 28.190103ms
> I0208 18:54:59.703831 30908 replica.cpp:320] Persisted replica status to
> STARTING
> I0208 18:54:59.704115 30908 recover.cpp:473] Replica is in STARTING status
> I0208 18:54:59.705976 30908 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (14363)@172.17.0.3:46699
> I0208 18:54:59.706395 30908 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I0208 18:54:59.707114 30908 recover.cpp:564] Updating replica status to
> VOTING
> I0208 18:54:59.737870 30909 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 30.473061ms
> I0208 18:54:59.737967 30909 replica.cpp:320] Persisted replica status to
> VOTING
> I0208 18:54:59.738236 30909 recover.cpp:578] Successfully joined the Paxos
> group
> I0208 18:54:59.738418 30909 recover.cpp:462] Recover process terminated
> I0208 18:54:59.739172 30909 log.cpp:659] Attempting to start the writer
> I0208 18:54:59.740962 30909 replica.cpp:493] Replica received implicit
> promise request from (14364)@172.17.0.3:46699 with proposal 1
> I0208 18:54:59.771332 30909 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 30.288877ms
> I0208 18:54:59.771428 30909 replica.cpp:342] Persisted promised to 1
> I0208 18:54:59.772766 30909 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0208 18:54:59.774515 30915 replica.cpp:388] Replica received explicit
> promise request from (14365)@172.17.0.3:46699 for position 0 with
> proposal 2
> I0208 18:54:59.804795 30915 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 30.198609ms
> I0208 18:54:59.804893 30915 replica.cpp:712] Persisted action at 0
> I0208 18:54:59.806735 30915 replica.cpp:537] Replica received write
> request for position 0 from (14366)@172.17.0.3:46699
> I0208 18:54:59.806819 30915 leveldb.cpp:436] Reading position from leveldb
> took 58582ns
> I0208 18:54:59.833856 30915 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 27.019885ms
> I0208 18:54:59.833957 30915 replica.cpp:712] Persisted action at 0
> I0208 18:54:59.841922 30902 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0208 18:54:59.867244 30902 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 25.248587ms
> I0208 18:54:59.867300 30902 replica.cpp:712] Persisted action at 0
> I0208 18:54:59.867326 30902 replica.cpp:697] Replica learned NOP action at
> position 0
> I0208 18:54:59.868388 30902 log.cpp:675] Writer started with ending
> position 0
> I0208 18:54:59.869875 30902 leveldb.cpp:436] Reading position from leveldb
> took 77691ns
> I0208 18:54:59.871314 30902 registrar.cpp:340] Successfully fetched the
> registry (0B) in 170.928128ms
> I0208 18:54:59.871474 30902 registrar.cpp:439] Applied 1 operations in
> 53910ns; attempting to update the 'registry'
> I0208 18:54:59.872838 30902 log.cpp:683] Attempting to append 170 bytes to
> the log
> I0208 18:54:59.873090 30902 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0208 18:54:59.874408 30902 replica.cpp:537] Replica received write
> request for position 1 from (14367)@172.17.0.3:46699
> I0208 18:54:59.900756 30902 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 26.326342ms
> I0208 18:54:59.901283 30902 replica.cpp:712] Persisted action at 1
> I0208 18:54:59.911499 30911 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I0208 18:54:59.934198 30911 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 21.941942ms
> I0208 18:54:59.934312 30911 replica.cpp:712] Persisted action at 1
> I0208 18:54:59.934350 30911 replica.cpp:697] Replica learned APPEND action
> at position 1
> I0208 18:54:59.936369 30903 log.cpp:702] Attempting to truncate the log to
> 1
> I0208 18:54:59.936554 30905 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I0208 18:54:59.936375 30913 registrar.cpp:484] Successfully updated the
> 'registry' in 64.78208ms
> I0208 18:54:59.937690 30903 replica.cpp:537] Replica received write
> request for position 2 from (14368)@172.17.0.3:46699
> I0208 18:54:59.937969 30913 registrar.cpp:370] Successfully recovered
> registrar
> I0208 18:54:59.938452 30913 master.cpp:1522] Recovered 0 slaves from the
> Registry (131B) ; allowing 10mins for slaves to re-register
> I0208 18:54:59.938691 30913 hierarchical.cpp:171] Skipping recovery of
> hierarchical allocator: nothing to recover
> I0208 18:54:59.959329 30903 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 21.618776ms
> I0208 18:54:59.959431 30903 replica.cpp:712] Persisted action at 2
> I0208 18:54:59.960988 30903 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I0208 18:54:59.984447 30903 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 23.45595ms
> I0208 18:54:59.984632 30903 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 100011ns
> I0208 18:54:59.984661 30903 replica.cpp:712] Persisted action at 2
> I0208 18:54:59.984697 30903 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I0208 18:54:59.993151 30884 scheduler.cpp:154] Version: 0.28.0
> I0208 18:54:59.994057 30904 scheduler.cpp:236] New master detected at
> master@172.17.0.3:46699
> I0208 18:54:59.995599 30904 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.0.3:46699
> I0208 18:54:59.998322 30916 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0208 18:54:59.998875 30916 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.3:51942
> I0208 18:54:59.999104 30916 master.cpp:1974] Received subscription request
> for HTTP framework 'default'
> I0208 18:54:59.999125 30916 master.cpp:1751] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I0208 18:54:59.999516 30916 master.cpp:2065] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I0208 18:55:00.000095 30916 hierarchical.cpp:265] Added framework
> 57643a42-47d1-44e2-bb42-d7b37fd29367-0000
> I0208 18:55:00.000185 30916 hierarchical.cpp:1403] No resources available
> to allocate!
> I0208 18:55:00.000264 30916 hierarchical.cpp:1498] No inverse offers to
> send out!
> I0208 18:55:00.000457 30916 hierarchical.cpp:1096] Performed allocation
> for 0 slaves in 353186ns
> I0208 18:55:00.000267 30914 master.hpp:1658] Sending heartbeat to
> 57643a42-47d1-44e2-bb42-d7b37fd29367-0000
> I0208 18:55:00.002504 30910 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.0.3:46699
> I0208 18:55:00.003248 30910 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.0.3:46699
> I0208 18:55:00.004165 30910 master_maintenance_tests.cpp:177] Ignoring
> HEARTBEAT event
> I0208 18:55:00.005381 30910 scheduler.cpp:298] Sending REQUEST call to
> master@172.17.0.3:46699
> I0208 18:55:00.007500 30917 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0208 18:55:00.007988 30903 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.3:51943
> I0208 18:55:00.008118 30903 master.cpp:2719] Processing REQUEST call for
> framework 57643a42-47d1-44e2-bb42-d7b37fd29367-0000 (default)
> I0208 18:55:00.008396 30903 hierarchical.cpp:589] Received resource
> request from framework 57643a42-47d1-44e2-bb42-d7b37fd29367-0000
> I0208 18:55:00.009335 30884 master.cpp:1027] Master terminating
> I0208 18:55:00.010391 30915 hierarchical.cpp:326] Removed framework
> 57643a42-47d1-44e2-bb42-d7b37fd29367-0000
> E0208 18:55:00.012096 30907 scheduler.cpp:431] End-Of-File received from
> master. The master closed the event stream
> [ OK ] ContentType/SchedulerTest.Request/0 (504 ms)
> [ RUN ] ContentType/SchedulerTest.Request/1
> I0208 18:55:00.109006 30884 leveldb.cpp:174] Opened db in 79.961824ms
> I0208 18:55:00.159271 30884 leveldb.cpp:181] Compacted db in 49.719181ms
> I0208 18:55:00.159381 30884 leveldb.cpp:196] Created db iterator in 41476ns
> I0208 18:55:00.159406 30884 leveldb.cpp:202] Seeked to beginning of db in
> 11971ns
> I0208 18:55:00.159421 30884 leveldb.cpp:271] Iterated through 0 keys in
> the db in 8477ns
> I0208 18:55:00.159482 30884 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0208 18:55:00.160254 30912 recover.cpp:447] Starting replica recovery
> I0208 18:55:00.161846 30914 recover.cpp:473] Replica is in EMPTY status
> I0208 18:55:00.163642 30914 replica.cpp:673] Replica in EMPTY status
> received a broadcasted recover request from (14376)@172.17.0.3:46699
> I0208 18:55:00.164237 30915 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I0208 18:55:00.164901 30915 recover.cpp:564] Updating replica status to
> STARTING
> I0208 18:55:00.166333 30904 master.cpp:376] Master
> 806c1fcb-16a0-4766-b8e0-2b9ea9cda543 (861577ef7168) started on
> 172.17.0.3:46699
> I0208 18:55:00.166369 30904 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/ha6nll/credentials"
> --framework_sorter="drf" --help="false" --hostname_lookup="true"
> --http_authenticators="basic" --initialize_driver_logging="true"
> --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO"
> --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000"
> --max_slave_ping_timeouts="5" --quiet="false"
> --recovery_slave_removal_limit="100%" --registry="replicated_log"
> --registry_fetch_timeout="1mins" --registry_store_timeout="100secs"
> --registry_strict="true" --root_submissions="true"
> --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins"
> --user_sorter="drf" --version="false"
> --webui_dir="/mesos/mesos-0.28.0/_inst/share/mesos/webui"
> --work_dir="/tmp/ha6nll/master" --zk_session_timeout="10secs"
> I0208 18:55:00.166748 30904 master.cpp:425] Master allowing
> unauthenticated frameworks to register
> I0208 18:55:00.166760 30904 master.cpp:428] Master only allowing
> authenticated slaves to register
> I0208 18:55:00.166769 30904 credentials.hpp:35] Loading credentials for
> authentication from '/tmp/ha6nll/credentials'
> I0208 18:55:00.167142 30904 master.cpp:468] Using default 'crammd5'
> authenticator
> I0208 18:55:00.167593 30904 master.cpp:537] Using default 'basic' HTTP
> authenticator
> I0208 18:55:00.167901 30904 master.cpp:571] Authorization enabled
> I0208 18:55:00.168329 30916 hierarchical.cpp:144] Initialized hierarchical
> allocator process
> I0208 18:55:00.168401 30916 whitelist_watcher.cpp:77] No whitelist given
> I0208 18:55:00.172530 30910 master.cpp:1712] The newly elected leader is
> master@172.17.0.3:46699 with id 806c1fcb-16a0-4766-b8e0-2b9ea9cda543
> I0208 18:55:00.172590 30910 master.cpp:1725] Elected as the leading master!
> I0208 18:55:00.172605 30910 master.cpp:1470] Recovering from registrar
> I0208 18:55:00.172871 30910 registrar.cpp:307] Recovering registrar
> I0208 18:55:00.209309 30915 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 44.202155ms
> I0208 18:55:00.209413 30915 replica.cpp:320] Persisted replica status to
> STARTING
> I0208 18:55:00.209852 30913 recover.cpp:473] Replica is in STARTING status
> I0208 18:55:00.211233 30904 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (14378)@172.17.0.3:46699
> I0208 18:55:00.211670 30915 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I0208 18:55:00.212086 30916 recover.cpp:564] Updating replica status to
> VOTING
> I0208 18:55:00.243320 30914 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 31.109594ms
> I0208 18:55:00.243422 30914 replica.cpp:320] Persisted replica status to
> VOTING
> I0208 18:55:00.243700 30914 recover.cpp:578] Successfully joined the Paxos
> group
> I0208 18:55:00.243897 30914 recover.cpp:462] Recover process terminated
> I0208 18:55:00.244657 30904 log.cpp:659] Attempting to start the writer
> I0208 18:55:00.246135 30914 replica.cpp:493] Replica received implicit
> promise request from (14379)@172.17.0.3:46699 with proposal 1
> I0208 18:55:00.276316 30914 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 30.167749ms
> I0208 18:55:00.276418 30914 replica.cpp:342] Persisted promised to 1
> I0208 18:55:00.277819 30916 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0208 18:55:00.279662 30916 replica.cpp:388] Replica received explicit
> promise request from (14380)@172.17.0.3:46699 for position 0 with
> proposal 2
> I0208 18:55:00.310320 30916 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 30.62787ms
> I0208 18:55:00.310422 30916 replica.cpp:712] Persisted action at 0
> I0208 18:55:00.312407 30915 replica.cpp:537] Replica received write
> request for position 0 from (14381)@172.17.0.3:46699
> I0208 18:55:00.312510 30915 leveldb.cpp:436] Reading position from leveldb
> took 61545ns
> I0208 18:55:00.343324 30915 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 30.794678ms
> I0208 18:55:00.343430 30915 replica.cpp:712] Persisted action at 0
> I0208 18:55:00.344425 30907 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0208 18:55:00.376541 30907 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 32.111427ms
> I0208 18:55:00.376642 30907 replica.cpp:712] Persisted action at 0
> I0208 18:55:00.376679 30907 replica.cpp:697] Replica learned NOP action at
> position 0
> I0208 18:55:00.377739 30915 log.cpp:675] Writer started with ending
> position 0
> I0208 18:55:00.379324 30915 leveldb.cpp:436] Reading position from leveldb
> took 80488ns
> I0208 18:55:00.380916 30915 registrar.cpp:340] Successfully fetched the
> registry (0B) in 208.007168ms
> I0208 18:55:00.381503 30915 registrar.cpp:439] Applied 1 operations in
> 144342ns; attempting to update the 'registry'
> I0208 18:55:00.382750 30913 log.cpp:683] Attempting to append 170 bytes to
> the log
> I0208 18:55:00.383303 30905 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0208 18:55:00.384769 30916 replica.cpp:537] Replica received write
> request for position 1 from (14382)@172.17.0.3:46699
> I0208 18:55:00.418339 30916 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 33.479734ms
> I0208 18:55:00.418572 30916 replica.cpp:712] Persisted action at 1
> I0208 18:55:00.420567 30908 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I0208 18:55:00.452318 30908 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 31.57526ms
> I0208 18:55:00.452425 30908 replica.cpp:712] Persisted action at 1
> I0208 18:55:00.452467 30908 replica.cpp:697] Replica learned APPEND action
> at position 1
> I0208 18:55:00.454103 30914 registrar.cpp:484] Successfully updated the
> 'registry' in 72.360192ms
> I0208 18:55:00.454267 30914 registrar.cpp:370] Successfully recovered
> registrar
> I0208 18:55:00.454449 30905 log.cpp:702] Attempting to truncate the log to
> 1
> I0208 18:55:00.454635 30905 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I0208 18:55:00.454731 30914 master.cpp:1522] Recovered 0 slaves from the
> Registry (131B) ; allowing 10mins for slaves to re-register
> I0208 18:55:00.454953 30914 hierarchical.cpp:171] Skipping recovery of
> hierarchical allocator: nothing to recover
> I0208 18:55:00.455977 30905 replica.cpp:537] Replica received write
> request for position 2 from (14383)@172.17.0.3:46699
> I0208 18:55:00.480638 30905 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 24.649183ms
> I0208 18:55:00.480747 30905 replica.cpp:712] Persisted action at 2
> I0208 18:55:00.491260 30913 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I0208 18:55:00.514071 30913 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 22.778615ms
> I0208 18:55:00.514407 30913 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 101055ns
> I0208 18:55:00.514468 30913 replica.cpp:712] Persisted action at 2
> I0208 18:55:00.514511 30913 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I0208 18:55:00.523013 30884 scheduler.cpp:154] Version: 0.28.0
> I0208 18:55:00.524111 30903 scheduler.cpp:236] New master detected at
> master@172.17.0.3:46699
> I0208 18:55:00.527071 30913 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.0.3:46699
> I0208 18:55:00.529314 30913 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0208 18:55:00.529911 30913 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.3:51944
> I0208 18:55:00.530346 30913 master.cpp:1974] Received subscription request
> for HTTP framework 'default'
> I0208 18:55:00.530375 30913 master.cpp:1751] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I0208 18:55:00.530720 30913 master.cpp:2065] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I0208 18:55:00.531250 30905 hierarchical.cpp:265] Added framework
> 806c1fcb-16a0-4766-b8e0-2b9ea9cda543-0000
> I0208 18:55:00.531345 30905 hierarchical.cpp:1403] No resources available
> to allocate!
> I0208 18:55:00.531391 30905 hierarchical.cpp:1498] No inverse offers to
> send out!
> I0208 18:55:00.531426 30905 hierarchical.cpp:1096] Performed allocation
> for 0 slaves in 161572ns
> I0208 18:55:00.531466 30905 master.hpp:1658] Sending heartbeat to
> 806c1fcb-16a0-4766-b8e0-2b9ea9cda543-0000
> I0208 18:55:00.532604 30910 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.0.3:46699
> I0208 18:55:00.533082 30910 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.0.3:46699
> I0208 18:55:00.534056 30902 master_maintenance_tests.cpp:177] Ignoring
> HEARTBEAT event
> I0208 18:55:00.534090 30910 scheduler.cpp:298] Sending REQUEST call to
> master@172.17.0.3:46699
> I0208 18:55:00.536380 30905 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0208 18:55:00.537015 30905 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.3:51945
> I0208 18:55:00.537401 30905 master.cpp:2719] Processing REQUEST call for
> framework 806c1fcb-16a0-4766-b8e0-2b9ea9cda543-0000 (default)
> I0208 18:55:00.537688 30905 hierarchical.cpp:589] Received resource
> request from framework 806c1fcb-16a0-4766-b8e0-2b9ea9cda543-0000
> I0208 18:55:00.539427 30909 master.cpp:1027] Master terminating
> I0208 18:55:00.539646 30905 hierarchical.cpp:326] Removed framework
> 806c1fcb-16a0-4766-b8e0-2b9ea9cda543-0000
> E0208 18:55:00.540665 30909 scheduler.cpp:431] End-Of-File received from
> master. The master closed the event stream
> [ OK ] ContentType/SchedulerTest.Request/1 (524 ms)
> [----------] 22 tests from ContentType/SchedulerTest (16580 ms total)
>
> [----------] Global test environment tear-down
> [==========] 968 tests from 126 test cases ran. (789114 ms total)
> [ PASSED ] 967 tests.
> [ FAILED ] 1 test, listed below:
> [ FAILED ] SlaveRecoveryTest/0.CleanupHTTPExecutor, where TypeParam =
> mesos::internal::slave::MesosContainerizer
>
> 1 FAILED TEST
> YOU HAVE 8 DISABLED TESTS
>
> make[4]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
> make[4]: *** [check-local] Error 1
> make[3]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
> make[3]: *** [check-am] Error 2
> make[2]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
> make[2]: *** [check] Error 2
> make[1]: Leaving directory `/mesos/mesos-0.28.0/_build'
> make[1]: *** [check-recursive] Error 1
> make: *** [distcheck] Error 1
> + docker rmi mesos-1454954742-22642
> Untagged: mesos-1454954742-22642:latest
> Deleted: 0d9e0d923493d0fc07faff27c595dcefa28a886e2cd3b68aadb56e24de769437
> Deleted: ec735037b6048f2f9025c631e8938c4923631d2ec48eef03d3d8b4d3117e2253
> Deleted: d2fa3526079cfa6aaaf804fbfd97958b172e390844204b61e08b7817a9797def
> Deleted: 2dfd8d20e96b043674465d3a2fa6e01d68594e81f5c6edb8a599f1861acf4b61
> Deleted: 59d0f677f9cd3f7119b25b1a1f99dd2adefe9f7c4b012fa0d30dfa565a097028
> Deleted: 16d34e4775e4cbbd800da402ab730edeefe1a8f050b02c25984620b986f65edb
> Deleted: dd8b9e0ef0a3456d3069658758694021673247e3a6cf60c2b8ee0aef66eee124
> Deleted: 3202e19891c1f4c1b84b26abb2a0f68d2ce0aab479c7f2ce50c5e73cb9d1a02f
> Deleted: 83e8c3fe9d0889f5872352277e762829ce98ca4cb34dcd67828a5347de77d58b
> Deleted: f18ffa77fb65aa841bb89f83aa07230c3fe1375b4fb47e7e7bbdfadfea4fa9a0
> Deleted: c462c96f02f531a53321d17070905c0397011df1c665147ca862c970a7ba879f
> Deleted: ee4f8495dfe7410d3a0d1d0296de9d40c0e71c8271157f4475c77bac969beabb
> Deleted: 3f010db7aa066c9261b8bee900bc5b94c214a2b9acf91109260d5cae05c0488d
> Deleted: 39d3825bba0616f4bf44022b26059f88333237ab3a02acccb4986bcc474d21dd
> Deleted: a8b03faf767250f483ad43e4e13ed3e6aaa5bf07969cc7c3dc7592a518f5e6ac
> Deleted: 43493d4650ea40244a34af781dc619caeb843efb5e922c614d461be127cb0ccb
> 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 #1639
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/1639/changes>