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/24 03:00:05 UTC
Build failed in Jenkins: Mesos » gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1) #1858
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1858/changes>
Changes:
[yujie.jay] Revert "Added Appc provisioner integration test."
------------------------------------------
[...truncated 174630 lines...]
I0324 01:59:52.777376 2759 credentials.hpp:35] Loading credentials for authentication from '/tmp/nHuIed/credentials'
I0324 01:59:52.778204 2764 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (15633)@172.17.0.1:46562
I0324 01:59:52.777783 2759 master.cpp:468] Using default 'crammd5' authenticator
I0324 01:59:52.778547 2759 master.cpp:537] Using default 'basic' HTTP authenticator
I0324 01:59:52.778707 2759 master.cpp:571] Authorization enabled
I0324 01:59:52.778708 2753 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0324 01:59:52.778884 2766 whitelist_watcher.cpp:77] No whitelist given
I0324 01:59:52.778894 2761 hierarchical.cpp:144] Initialized hierarchical allocator process
I0324 01:59:52.779484 2765 recover.cpp:564] Updating replica status to STARTING
I0324 01:59:52.781863 2761 master.cpp:1806] The newly elected leader is master@172.17.0.1:46562 with id bbe69e96-601d-4a3f-b185-02fc26976083
I0324 01:59:52.781910 2761 master.cpp:1819] Elected as the leading master!
I0324 01:59:52.781929 2761 master.cpp:1508] Recovering from registrar
I0324 01:59:52.782061 2768 registrar.cpp:307] Recovering registrar
I0324 01:59:52.814800 2757 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 35.062586ms
I0324 01:59:52.814906 2757 replica.cpp:320] Persisted replica status to STARTING
I0324 01:59:52.815259 2753 recover.cpp:473] Replica is in STARTING status
I0324 01:59:52.816689 2753 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (15635)@172.17.0.1:46562
I0324 01:59:52.817148 2764 recover.cpp:193] Received a recover response from a replica in STARTING status
I0324 01:59:52.817718 2766 recover.cpp:564] Updating replica status to VOTING
I0324 01:59:52.862365 2758 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 44.370458ms
I0324 01:59:52.862468 2758 replica.cpp:320] Persisted replica status to VOTING
I0324 01:59:52.862778 2756 recover.cpp:578] Successfully joined the Paxos group
I0324 01:59:52.863081 2756 recover.cpp:462] Recover process terminated
I0324 01:59:52.863757 2761 log.cpp:659] Attempting to start the writer
I0324 01:59:52.865320 2757 replica.cpp:493] Replica received implicit promise request from (15636)@172.17.0.1:46562 with proposal 1
I0324 01:59:52.921033 2757 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 55.638764ms
I0324 01:59:52.921130 2757 replica.cpp:342] Persisted promised to 1
I0324 01:59:52.922557 2757 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0324 01:59:52.924376 2757 replica.cpp:388] Replica received explicit promise request from (15637)@172.17.0.1:46562 for position 0 with proposal 2
I0324 01:59:53.005530 2757 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 81.069179ms
I0324 01:59:53.005638 2757 replica.cpp:712] Persisted action at 0
I0324 01:59:53.007601 2761 replica.cpp:537] Replica received write request for position 0 from (15638)@172.17.0.1:46562
I0324 01:59:53.007712 2761 leveldb.cpp:436] Reading position from leveldb took 49385ns
I0324 01:59:53.089365 2761 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 81.579717ms
I0324 01:59:53.089467 2761 replica.cpp:712] Persisted action at 0
I0324 01:59:53.090592 2761 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0324 01:59:53.140779 2761 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 50.120199ms
I0324 01:59:53.140878 2761 replica.cpp:712] Persisted action at 0
I0324 01:59:53.140915 2761 replica.cpp:697] Replica learned NOP action at position 0
I0324 01:59:53.142105 2761 log.cpp:675] Writer started with ending position 0
I0324 01:59:53.143754 2767 leveldb.cpp:436] Reading position from leveldb took 63282ns
I0324 01:59:53.145073 2759 registrar.cpp:340] Successfully fetched the registry (0B) in 362.944768ms
I0324 01:59:53.145226 2759 registrar.cpp:439] Applied 1 operations in 45820ns; attempting to update the 'registry'
I0324 01:59:53.146119 2759 log.cpp:683] Attempting to append 170 bytes to the log
I0324 01:59:53.146293 2762 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0324 01:59:53.147415 2753 replica.cpp:537] Replica received write request for position 1 from (15639)@172.17.0.1:46562
I0324 01:59:53.191391 2753 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 43.907878ms
I0324 01:59:53.191478 2753 replica.cpp:712] Persisted action at 1
I0324 01:59:53.192682 2755 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0324 01:59:53.222986 2755 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 30.236931ms
I0324 01:59:53.223093 2755 replica.cpp:712] Persisted action at 1
I0324 01:59:53.223132 2755 replica.cpp:697] Replica learned APPEND action at position 1
I0324 01:59:53.224637 2768 registrar.cpp:484] Successfully updated the 'registry' in 79.323904ms
I0324 01:59:53.224798 2768 registrar.cpp:370] Successfully recovered registrar
I0324 01:59:53.224864 2755 log.cpp:702] Attempting to truncate the log to 1
I0324 01:59:53.225070 2768 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0324 01:59:53.225280 2755 master.cpp:1616] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0324 01:59:53.225486 2755 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0324 01:59:53.226243 2755 replica.cpp:537] Replica received write request for position 2 from (15640)@172.17.0.1:46562
I0324 01:59:53.305188 2755 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 78.873137ms
I0324 01:59:53.305291 2755 replica.cpp:712] Persisted action at 2
I0324 01:59:53.306680 2760 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0324 01:59:53.372323 2760 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 65.57018ms
I0324 01:59:53.372510 2760 leveldb.cpp:399] Deleting ~1 keys from leveldb took 84908ns
I0324 01:59:53.372541 2760 replica.cpp:712] Persisted action at 2
I0324 01:59:53.372581 2760 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0324 01:59:53.377761 2734 scheduler.cpp:172] Version: 0.29.0
I0324 01:59:53.378828 2764 scheduler.cpp:437] New master detected at master@172.17.0.1:46562
I0324 01:59:53.380762 2764 scheduler.cpp:337] Connected with the master at http://172.17.0.1:46562/master/api/v1/scheduler
I0324 01:59:53.382339 2764 scheduler.cpp:230] Sending SUBSCRIBE call to http://172.17.0.1:46562/master/api/v1/scheduler
I0324 01:59:53.383646 2763 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0324 01:59:53.384330 2763 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:53088
I0324 01:59:53.384630 2763 master.cpp:2068] Received subscription request for HTTP framework 'default'
I0324 01:59:53.384706 2763 master.cpp:1845] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0324 01:59:53.385548 2763 master.cpp:2159] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0324 01:59:53.386773 2768 master.hpp:1715] Sending heartbeat to bbe69e96-601d-4a3f-b185-02fc26976083-0000
I0324 01:59:53.386924 2763 hierarchical.cpp:265] Added framework bbe69e96-601d-4a3f-b185-02fc26976083-0000
I0324 01:59:53.387034 2763 hierarchical.cpp:1466] No resources available to allocate!
I0324 01:59:53.387070 2763 hierarchical.cpp:1561] No inverse offers to send out!
I0324 01:59:53.387136 2763 hierarchical.cpp:1138] Performed allocation for 0 slaves in 183560ns
I0324 01:59:53.388620 2768 scheduler.cpp:612] Enqueuing event SUBSCRIBED received from http://172.17.0.1:46562/master/api/v1/scheduler
I0324 01:59:53.389505 2768 scheduler.cpp:612] Enqueuing event HEARTBEAT received from http://172.17.0.1:46562/master/api/v1/scheduler
I0324 01:59:53.390867 2768 scheduler.cpp:230] Sending REQUEST call to http://172.17.0.1:46562/master/api/v1/scheduler
I0324 01:59:53.392192 2768 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0324 01:59:53.392789 2768 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:53089
I0324 01:59:53.392930 2768 master.cpp:2765] Processing REQUEST call for framework bbe69e96-601d-4a3f-b185-02fc26976083-0000 (default)
I0324 01:59:53.393173 2768 hierarchical.cpp:589] Received resource request from framework bbe69e96-601d-4a3f-b185-02fc26976083-0000
I0324 01:59:53.396083 2766 master.cpp:1251] Framework bbe69e96-601d-4a3f-b185-02fc26976083-0000 (default) disconnected
I0324 01:59:53.396126 2766 master.cpp:2622] Disconnecting framework bbe69e96-601d-4a3f-b185-02fc26976083-0000 (default)
I0324 01:59:53.396149 2766 master.cpp:2646] Deactivating framework bbe69e96-601d-4a3f-b185-02fc26976083-0000 (default)
I0324 01:59:53.396252 2766 master.cpp:1275] Giving framework bbe69e96-601d-4a3f-b185-02fc26976083-0000 (default) 0ns to failover
I0324 01:59:53.396294 2755 hierarchical.cpp:375] Deactivated framework bbe69e96-601d-4a3f-b185-02fc26976083-0000
I0324 01:59:53.397702 2755 master.cpp:5340] Framework failover timeout, removing framework bbe69e96-601d-4a3f-b185-02fc26976083-0000 (default)
I0324 01:59:53.397732 2755 master.cpp:6073] Removing framework bbe69e96-601d-4a3f-b185-02fc26976083-0000 (default)
I0324 01:59:53.398002 2755 hierarchical.cpp:326] Removed framework bbe69e96-601d-4a3f-b185-02fc26976083-0000
I0324 01:59:53.406644 2734 master.cpp:1065] Master terminating
[ OK ] ContentType/SchedulerTest.Request/0 (933 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I0324 01:59:53.429944 2734 cluster.cpp:139] Creating default 'local' authorizer
I0324 01:59:53.535195 2734 leveldb.cpp:174] Opened db in 104.924303ms
I0324 01:59:53.577211 2734 leveldb.cpp:181] Compacted db in 41.913412ms
I0324 01:59:53.577343 2734 leveldb.cpp:196] Created db iterator in 34927ns
I0324 01:59:53.577365 2734 leveldb.cpp:202] Seeked to beginning of db in 4623ns
I0324 01:59:53.577378 2734 leveldb.cpp:271] Iterated through 0 keys in the db in 486ns
I0324 01:59:53.577436 2734 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0324 01:59:53.578289 2755 recover.cpp:447] Starting replica recovery
I0324 01:59:53.578778 2755 recover.cpp:473] Replica is in EMPTY status
I0324 01:59:53.580142 2762 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (15648)@172.17.0.1:46562
I0324 01:59:53.580806 2764 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0324 01:59:53.581454 2764 recover.cpp:564] Updating replica status to STARTING
I0324 01:59:53.584010 2768 master.cpp:376] Master affcdd6a-38fb-4701-9a9a-79641889a306 (906fac92cd84) started on 172.17.0.1:46562
I0324 01:59:53.584048 2768 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/1P3Nwe/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/1P3Nwe/master" --zk_session_timeout="10secs"
I0324 01:59:53.584475 2768 master.cpp:425] Master allowing unauthenticated frameworks to register
I0324 01:59:53.584487 2768 master.cpp:428] Master only allowing authenticated slaves to register
I0324 01:59:53.584496 2768 credentials.hpp:35] Loading credentials for authentication from '/tmp/1P3Nwe/credentials'
I0324 01:59:53.584903 2768 master.cpp:468] Using default 'crammd5' authenticator
I0324 01:59:53.585074 2768 master.cpp:537] Using default 'basic' HTTP authenticator
I0324 01:59:53.585232 2768 master.cpp:571] Authorization enabled
I0324 01:59:53.585424 2756 hierarchical.cpp:144] Initialized hierarchical allocator process
I0324 01:59:53.585424 2762 whitelist_watcher.cpp:77] No whitelist given
I0324 01:59:53.588595 2756 master.cpp:1806] The newly elected leader is master@172.17.0.1:46562 with id affcdd6a-38fb-4701-9a9a-79641889a306
I0324 01:59:53.588671 2756 master.cpp:1819] Elected as the leading master!
I0324 01:59:53.588693 2756 master.cpp:1508] Recovering from registrar
I0324 01:59:53.588969 2756 registrar.cpp:307] Recovering registrar
I0324 01:59:53.608121 2764 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 26.320382ms
I0324 01:59:53.608225 2764 replica.cpp:320] Persisted replica status to STARTING
I0324 01:59:53.608587 2758 recover.cpp:473] Replica is in STARTING status
I0324 01:59:53.610802 2758 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (15650)@172.17.0.1:46562
I0324 01:59:53.611289 2758 recover.cpp:193] Received a recover response from a replica in STARTING status
I0324 01:59:53.612115 2758 recover.cpp:564] Updating replica status to VOTING
I0324 01:59:53.650141 2758 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 37.746297ms
I0324 01:59:53.650241 2758 replica.cpp:320] Persisted replica status to VOTING
I0324 01:59:53.650511 2758 recover.cpp:578] Successfully joined the Paxos group
I0324 01:59:53.650724 2758 recover.cpp:462] Recover process terminated
I0324 01:59:53.651523 2758 log.cpp:659] Attempting to start the writer
I0324 01:59:53.653301 2758 replica.cpp:493] Replica received implicit promise request from (15651)@172.17.0.1:46562 with proposal 1
I0324 01:59:53.692936 2758 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 39.558527ms
I0324 01:59:53.693038 2758 replica.cpp:342] Persisted promised to 1
I0324 01:59:53.694489 2758 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0324 01:59:53.696542 2758 replica.cpp:388] Replica received explicit promise request from (15652)@172.17.0.1:46562 for position 0 with proposal 2
I0324 01:59:53.753900 2758 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 57.088419ms
I0324 01:59:53.753995 2758 replica.cpp:712] Persisted action at 0
I0324 01:59:53.764423 2759 replica.cpp:537] Replica received write request for position 0 from (15653)@172.17.0.1:46562
I0324 01:59:53.764669 2759 leveldb.cpp:436] Reading position from leveldb took 70007ns
I0324 01:59:53.802037 2759 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 37.264898ms
I0324 01:59:53.802144 2759 replica.cpp:712] Persisted action at 0
I0324 01:59:53.803273 2759 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0324 01:59:53.843935 2759 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 40.603316ms
I0324 01:59:53.844043 2759 replica.cpp:712] Persisted action at 0
I0324 01:59:53.844085 2759 replica.cpp:697] Replica learned NOP action at position 0
I0324 01:59:53.845455 2759 log.cpp:675] Writer started with ending position 0
I0324 01:59:53.847097 2759 leveldb.cpp:436] Reading position from leveldb took 70566ns
I0324 01:59:53.848704 2760 registrar.cpp:340] Successfully fetched the registry (0B) in 259.668992ms
I0324 01:59:53.848922 2760 registrar.cpp:439] Applied 1 operations in 42652ns; attempting to update the 'registry'
I0324 01:59:53.850397 2760 log.cpp:683] Attempting to append 170 bytes to the log
I0324 01:59:53.850668 2766 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0324 01:59:53.851465 2767 replica.cpp:537] Replica received write request for position 1 from (15654)@172.17.0.1:46562
I0324 01:59:53.885751 2767 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 34.206509ms
I0324 01:59:53.885861 2767 replica.cpp:712] Persisted action at 1
I0324 01:59:53.887035 2756 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0324 01:59:53.927582 2756 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 40.458787ms
I0324 01:59:53.927690 2756 replica.cpp:712] Persisted action at 1
I0324 01:59:53.927727 2756 replica.cpp:697] Replica learned APPEND action at position 1
I0324 01:59:53.929910 2756 registrar.cpp:484] Successfully updated the 'registry' in 80.871936ms
I0324 01:59:53.930055 2756 registrar.cpp:370] Successfully recovered registrar
I0324 01:59:53.930316 2756 log.cpp:702] Attempting to truncate the log to 1
I0324 01:59:53.930807 2756 master.cpp:1616] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0324 01:59:53.930898 2756 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0324 01:59:53.931340 2756 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0324 01:59:53.932582 2761 replica.cpp:537] Replica received write request for position 2 from (15655)@172.17.0.1:46562
I0324 01:59:53.969418 2761 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 36.688329ms
I0324 01:59:53.969516 2761 replica.cpp:712] Persisted action at 2
I0324 01:59:53.971070 2761 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0324 01:59:54.011320 2761 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 40.190861ms
I0324 01:59:54.011497 2761 leveldb.cpp:399] Deleting ~1 keys from leveldb took 80849ns
I0324 01:59:54.011524 2761 replica.cpp:712] Persisted action at 2
I0324 01:59:54.011560 2761 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0324 01:59:54.013286 2734 scheduler.cpp:172] Version: 0.29.0
I0324 01:59:54.014456 2766 scheduler.cpp:437] New master detected at master@172.17.0.1:46562
I0324 01:59:54.016238 2763 scheduler.cpp:337] Connected with the master at http://172.17.0.1:46562/master/api/v1/scheduler
I0324 01:59:54.017628 2760 scheduler.cpp:230] Sending SUBSCRIBE call to http://172.17.0.1:46562/master/api/v1/scheduler
I0324 01:59:54.019076 2761 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0324 01:59:54.019712 2768 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:53095
I0324 01:59:54.020216 2768 master.cpp:2068] Received subscription request for HTTP framework 'default'
I0324 01:59:54.020298 2768 master.cpp:1845] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0324 01:59:54.020643 2768 master.cpp:2159] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0324 01:59:54.021092 2766 hierarchical.cpp:265] Added framework affcdd6a-38fb-4701-9a9a-79641889a306-0000
I0324 01:59:54.021208 2766 hierarchical.cpp:1466] No resources available to allocate!
I0324 01:59:54.021246 2766 hierarchical.cpp:1561] No inverse offers to send out!
I0324 01:59:54.021314 2766 hierarchical.cpp:1138] Performed allocation for 0 slaves in 197150ns
I0324 01:59:54.021575 2768 master.hpp:1715] Sending heartbeat to affcdd6a-38fb-4701-9a9a-79641889a306-0000
I0324 01:59:54.022439 2758 scheduler.cpp:612] Enqueuing event SUBSCRIBED received from http://172.17.0.1:46562/master/api/v1/scheduler
I0324 01:59:54.023023 2758 scheduler.cpp:612] Enqueuing event HEARTBEAT received from http://172.17.0.1:46562/master/api/v1/scheduler
I0324 01:59:54.024080 2757 scheduler.cpp:230] Sending REQUEST call to http://172.17.0.1:46562/master/api/v1/scheduler
I0324 01:59:54.025243 2765 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0324 01:59:54.025727 2765 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:53096
I0324 01:59:54.026037 2765 master.cpp:2765] Processing REQUEST call for framework affcdd6a-38fb-4701-9a9a-79641889a306-0000 (default)
I0324 01:59:54.026171 2762 hierarchical.cpp:589] Received resource request from framework affcdd6a-38fb-4701-9a9a-79641889a306-0000
I0324 01:59:54.028656 2764 master.cpp:1251] Framework affcdd6a-38fb-4701-9a9a-79641889a306-0000 (default) disconnected
I0324 01:59:54.028692 2764 master.cpp:2622] Disconnecting framework affcdd6a-38fb-4701-9a9a-79641889a306-0000 (default)
I0324 01:59:54.028720 2764 master.cpp:2646] Deactivating framework affcdd6a-38fb-4701-9a9a-79641889a306-0000 (default)
I0324 01:59:54.028851 2764 master.cpp:1275] Giving framework affcdd6a-38fb-4701-9a9a-79641889a306-0000 (default) 0ns to failover
I0324 01:59:54.028895 2756 hierarchical.cpp:375] Deactivated framework affcdd6a-38fb-4701-9a9a-79641889a306-0000
I0324 01:59:54.030294 2762 master.cpp:5340] Framework failover timeout, removing framework affcdd6a-38fb-4701-9a9a-79641889a306-0000 (default)
I0324 01:59:54.030328 2762 master.cpp:6073] Removing framework affcdd6a-38fb-4701-9a9a-79641889a306-0000 (default)
I0324 01:59:54.030632 2762 hierarchical.cpp:326] Removed framework affcdd6a-38fb-4701-9a9a-79641889a306-0000
I0324 01:59:54.039325 2734 master.cpp:1065] Master terminating
[ OK ] ContentType/SchedulerTest.Request/1 (618 ms)
[----------] 26 tests from ContentType/SchedulerTest (26680 ms total)
[----------] Global test environment tear-down
E0324 01:59:54.717273 2734 environment.cpp:778] Failed to remove '/tmp/LogZooKeeperTest_WriteRead_xTywq1': No such file or directory
E0324 01:59:54.717376 2734 environment.cpp:778] Failed to remove '/tmp/LogZooKeeperTest_LostZooKeeper_RLXhBA': No such file or directory
[==========] 1039 tests from 132 test cases ran. (895868 ms total)
[ PASSED ] 1038 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] TarTest.BZIP2CompressFile
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]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.29.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1458781816-2951
Untagged: mesos-1458781816-2951:latest
Deleted: f4239cae080adb51717a23452cad56b73bcdf4b72755e1949d37faf8dddf39e2
Deleted: af2b59f0e84380370a908efa62ba60373eb8fcaff742154ce6799329145efe87
Deleted: 2347363f54460d7ad0ba050ea829cef1bd0721c88aa1b72c36b2fba0bdc04434
Deleted: f0dfef9e19720e86273fc9390b45b3423f2ef108da1d932dccf6fc5c1d72cb17
Deleted: d518bb53223e4f843e4621cfa7d3c7dccf0077bcdb7d41d5798fc0f9d2b25ae9
Deleted: bfd5a8645510d50855745dd64ef8fb838f1f22bf6ed9139cbd5aa4e417cfdb40
Deleted: 3d9eb8c04b53012819d242576c9b97e50b1bfd048a99b2b26e4c8e8459c78fe4
Deleted: b1b68213b90fe519f6872fd1db05ce1df0ffdb9daf45336a4bb9a3ef1b1b2f33
Deleted: 63ab916cd75029d3b25267002cbfbdfa4893c28a0cc6ed0ab4241bf914da6c72
Deleted: 29f134abd8665f794adc0eec5c2186e611488e8534cfea84fd5b0a6c9a0d665f
Deleted: 85085d06231239a67138eee82d9c6906fa0ec41a65105a4c9b80e5e3bb2d2b89
Deleted: ee2f751d101156cf66f934fc32b4c99a0190dc5144fece15fd5386bc3290fb30
Deleted: 26b035f7c4882f73dbac9a36cf3e8c71b2dce94faa77cf4d551c1dc0e2b7c62d
Deleted: 7092e1cb538f543d6df4c02776666dde94f9117c5b6b217ee317db5f8866aa8e
Deleted: 64f0b61ce572506ad787e6867f7dbb4961bed4fe53909fcf824c8e58751370f9
Deleted: 46733e5def38bcd468f04e3530b004fa89907fb74a74a63807bdeeeaf0b02939
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos » gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1) #1860
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1860/changes>
Build failed in Jenkins: Mesos » gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1) #1859
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1859/changes>
Changes:
[bmahler] Fixed a memory leak in process::subprocess.
------------------------------------------
[...truncated 175324 lines...]
I0324 05:19:12.285522 2756 recover.cpp:447] Starting replica recovery
I0324 05:19:12.285990 2757 master.cpp:571] Authorization enabled
I0324 05:19:12.287668 2765 whitelist_watcher.cpp:77] No whitelist given
I0324 05:19:12.290014 2756 recover.cpp:473] Replica is in EMPTY status
I0324 05:19:12.290719 2752 hierarchical.cpp:144] Initialized hierarchical allocator process
I0324 05:19:12.292784 2756 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (15635)@172.17.0.2:43561
I0324 05:19:12.293954 2756 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0324 05:19:12.294692 2752 recover.cpp:564] Updating replica status to STARTING
I0324 05:19:12.297168 2757 master.cpp:1806] The newly elected leader is master@172.17.0.2:43561 with id 2027003b-6988-44e2-b6a6-1b08dd6864ca
I0324 05:19:12.297282 2757 master.cpp:1819] Elected as the leading master!
I0324 05:19:12.297390 2757 master.cpp:1508] Recovering from registrar
I0324 05:19:12.297669 2760 registrar.cpp:307] Recovering registrar
I0324 05:19:12.338743 2752 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 41.918126ms
I0324 05:19:12.338826 2752 replica.cpp:320] Persisted replica status to STARTING
I0324 05:19:12.339175 2752 recover.cpp:473] Replica is in STARTING status
I0324 05:19:12.341039 2752 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (15636)@172.17.0.2:43561
I0324 05:19:12.341433 2752 recover.cpp:193] Received a recover response from a replica in STARTING status
I0324 05:19:12.342141 2752 recover.cpp:564] Updating replica status to VOTING
I0324 05:19:12.377261 2752 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 34.882874ms
I0324 05:19:12.377348 2752 replica.cpp:320] Persisted replica status to VOTING
I0324 05:19:12.377578 2757 recover.cpp:578] Successfully joined the Paxos group
I0324 05:19:12.377867 2757 recover.cpp:462] Recover process terminated
I0324 05:19:12.378235 2767 log.cpp:659] Attempting to start the writer
I0324 05:19:12.379869 2755 replica.cpp:493] Replica received implicit promise request from (15637)@172.17.0.2:43561 with proposal 1
I0324 05:19:12.419039 2755 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 39.11417ms
I0324 05:19:12.419128 2755 replica.cpp:342] Persisted promised to 1
I0324 05:19:12.420135 2754 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0324 05:19:12.421528 2753 replica.cpp:388] Replica received explicit promise request from (15638)@172.17.0.2:43561 for position 0 with proposal 2
I0324 05:19:12.460842 2753 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 39.237227ms
I0324 05:19:12.460935 2753 replica.cpp:712] Persisted action at 0
I0324 05:19:12.462357 2754 replica.cpp:537] Replica received write request for position 0 from (15639)@172.17.0.2:43561
I0324 05:19:12.462438 2754 leveldb.cpp:436] Reading position from leveldb took 39751ns
I0324 05:19:12.502640 2754 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 40.135813ms
I0324 05:19:12.502738 2754 replica.cpp:712] Persisted action at 0
I0324 05:19:12.503854 2754 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0324 05:19:12.541704 2754 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 37.792334ms
I0324 05:19:12.541792 2754 replica.cpp:712] Persisted action at 0
I0324 05:19:12.541828 2754 replica.cpp:697] Replica learned NOP action at position 0
I0324 05:19:12.543035 2754 log.cpp:675] Writer started with ending position 0
I0324 05:19:12.544514 2754 leveldb.cpp:436] Reading position from leveldb took 62934ns
I0324 05:19:12.546075 2761 registrar.cpp:340] Successfully fetched the registry (0B) in 248.274176ms
I0324 05:19:12.546232 2761 registrar.cpp:439] Applied 1 operations in 39853ns; attempting to update the 'registry'
I0324 05:19:12.547446 2761 log.cpp:683] Attempting to append 170 bytes to the log
I0324 05:19:12.547742 2755 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0324 05:19:12.548749 2755 replica.cpp:537] Replica received write request for position 1 from (15640)@172.17.0.2:43561
I0324 05:19:12.575192 2755 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 26.377766ms
I0324 05:19:12.575279 2755 replica.cpp:712] Persisted action at 1
I0324 05:19:12.576699 2755 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0324 05:19:12.591974 2755 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 15.221704ms
I0324 05:19:12.592061 2755 replica.cpp:712] Persisted action at 1
I0324 05:19:12.592097 2755 replica.cpp:697] Replica learned APPEND action at position 1
I0324 05:19:12.594132 2755 registrar.cpp:484] Successfully updated the 'registry' in 47.825664ms
I0324 05:19:12.594290 2755 registrar.cpp:370] Successfully recovered registrar
I0324 05:19:12.594542 2755 log.cpp:702] Attempting to truncate the log to 1
I0324 05:19:12.595052 2755 master.cpp:1616] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0324 05:19:12.595155 2755 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0324 05:19:12.595625 2755 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0324 05:19:12.596698 2767 replica.cpp:537] Replica received write request for position 2 from (15641)@172.17.0.2:43561
I0324 05:19:12.617311 2767 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 20.547635ms
I0324 05:19:12.617396 2767 replica.cpp:712] Persisted action at 2
I0324 05:19:12.618839 2767 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0324 05:19:12.634109 2767 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 15.220391ms
I0324 05:19:12.634260 2767 leveldb.cpp:399] Deleting ~1 keys from leveldb took 70441ns
I0324 05:19:12.634287 2767 replica.cpp:712] Persisted action at 2
I0324 05:19:12.634323 2767 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0324 05:19:12.637189 2733 scheduler.cpp:172] Version: 0.29.0
I0324 05:19:12.638002 2763 scheduler.cpp:437] New master detected at master@172.17.0.2:43561
I0324 05:19:12.640465 2767 scheduler.cpp:337] Connected with the master at http://172.17.0.2:43561/master/api/v1/scheduler
I0324 05:19:12.641928 2758 scheduler.cpp:230] Sending SUBSCRIBE call to http://172.17.0.2:43561/master/api/v1/scheduler
I0324 05:19:12.643028 2759 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0324 05:19:12.643496 2759 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:51639
I0324 05:19:12.643734 2759 master.cpp:2068] Received subscription request for HTTP framework 'default'
I0324 05:19:12.643798 2759 master.cpp:1845] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0324 05:19:12.644043 2759 master.cpp:2159] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0324 05:19:12.644439 2765 hierarchical.cpp:265] Added framework 2027003b-6988-44e2-b6a6-1b08dd6864ca-0000
I0324 05:19:12.644528 2765 hierarchical.cpp:1466] No resources available to allocate!
I0324 05:19:12.644561 2765 hierarchical.cpp:1561] No inverse offers to send out!
I0324 05:19:12.644562 2759 master.hpp:1715] Sending heartbeat to 2027003b-6988-44e2-b6a6-1b08dd6864ca-0000
I0324 05:19:12.644628 2765 hierarchical.cpp:1138] Performed allocation for 0 slaves in 163668ns
I0324 05:19:12.645898 2759 scheduler.cpp:612] Enqueuing event SUBSCRIBED received from http://172.17.0.2:43561/master/api/v1/scheduler
I0324 05:19:12.646380 2759 scheduler.cpp:612] Enqueuing event HEARTBEAT received from http://172.17.0.2:43561/master/api/v1/scheduler
I0324 05:19:12.648236 2760 scheduler.cpp:230] Sending REQUEST call to http://172.17.0.2:43561/master/api/v1/scheduler
I0324 05:19:12.649540 2765 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0324 05:19:12.650074 2765 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:51640
I0324 05:19:12.650228 2765 master.cpp:2765] Processing REQUEST call for framework 2027003b-6988-44e2-b6a6-1b08dd6864ca-0000 (default)
I0324 05:19:12.650406 2755 hierarchical.cpp:589] Received resource request from framework 2027003b-6988-44e2-b6a6-1b08dd6864ca-0000
I0324 05:19:12.652356 2761 master.cpp:1251] Framework 2027003b-6988-44e2-b6a6-1b08dd6864ca-0000 (default) disconnected
I0324 05:19:12.652393 2761 master.cpp:2622] Disconnecting framework 2027003b-6988-44e2-b6a6-1b08dd6864ca-0000 (default)
I0324 05:19:12.652416 2761 master.cpp:2646] Deactivating framework 2027003b-6988-44e2-b6a6-1b08dd6864ca-0000 (default)
I0324 05:19:12.652510 2761 master.cpp:1275] Giving framework 2027003b-6988-44e2-b6a6-1b08dd6864ca-0000 (default) 0ns to failover
I0324 05:19:12.652572 2764 hierarchical.cpp:375] Deactivated framework 2027003b-6988-44e2-b6a6-1b08dd6864ca-0000
I0324 05:19:12.653898 2764 master.cpp:5340] Framework failover timeout, removing framework 2027003b-6988-44e2-b6a6-1b08dd6864ca-0000 (default)
I0324 05:19:12.653928 2764 master.cpp:6073] Removing framework 2027003b-6988-44e2-b6a6-1b08dd6864ca-0000 (default)
I0324 05:19:12.654227 2761 hierarchical.cpp:326] Removed framework 2027003b-6988-44e2-b6a6-1b08dd6864ca-0000
I0324 05:19:12.662643 2733 master.cpp:1065] Master terminating
[ OK ] ContentType/SchedulerTest.Request/0 (938 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I0324 05:19:12.672431 2733 cluster.cpp:139] Creating default 'local' authorizer
I0324 05:19:12.806680 2733 leveldb.cpp:174] Opened db in 133.932536ms
I0324 05:19:12.831961 2733 leveldb.cpp:181] Compacted db in 25.198798ms
I0324 05:19:12.832062 2733 leveldb.cpp:196] Created db iterator in 25765ns
I0324 05:19:12.832082 2733 leveldb.cpp:202] Seeked to beginning of db in 3097ns
I0324 05:19:12.832093 2733 leveldb.cpp:271] Iterated through 0 keys in the db in 395ns
I0324 05:19:12.832146 2733 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0324 05:19:12.833036 2763 recover.cpp:447] Starting replica recovery
I0324 05:19:12.833642 2763 recover.cpp:473] Replica is in EMPTY status
I0324 05:19:12.836365 2760 master.cpp:376] Master a946692a-9dc0-435c-8e59-0ba6441f5821 (e504de0cfa55) started on 172.17.0.2:43561
I0324 05:19:12.836406 2760 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/cBbKdw/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/cBbKdw/master" --zk_session_timeout="10secs"
I0324 05:19:12.836829 2760 master.cpp:425] Master allowing unauthenticated frameworks to register
I0324 05:19:12.836843 2760 master.cpp:428] Master only allowing authenticated slaves to register
I0324 05:19:12.836853 2760 credentials.hpp:35] Loading credentials for authentication from '/tmp/cBbKdw/credentials'
I0324 05:19:12.837265 2760 master.cpp:468] Using default 'crammd5' authenticator
I0324 05:19:12.837427 2760 master.cpp:537] Using default 'basic' HTTP authenticator
I0324 05:19:12.837563 2760 master.cpp:571] Authorization enabled
I0324 05:19:12.838243 2764 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (15650)@172.17.0.2:43561
I0324 05:19:12.838574 2767 hierarchical.cpp:144] Initialized hierarchical allocator process
I0324 05:19:12.838734 2755 whitelist_watcher.cpp:77] No whitelist given
I0324 05:19:12.838824 2762 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0324 05:19:12.839265 2764 recover.cpp:564] Updating replica status to STARTING
I0324 05:19:12.844112 2760 master.cpp:1806] The newly elected leader is master@172.17.0.2:43561 with id a946692a-9dc0-435c-8e59-0ba6441f5821
I0324 05:19:12.844183 2760 master.cpp:1819] Elected as the leading master!
I0324 05:19:12.844203 2760 master.cpp:1508] Recovering from registrar
I0324 05:19:12.844466 2760 registrar.cpp:307] Recovering registrar
I0324 05:19:12.865316 2764 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 25.756387ms
I0324 05:19:12.865412 2764 replica.cpp:320] Persisted replica status to STARTING
I0324 05:19:12.865797 2764 recover.cpp:473] Replica is in STARTING status
I0324 05:19:12.867724 2764 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (15651)@172.17.0.2:43561
I0324 05:19:12.868125 2764 recover.cpp:193] Received a recover response from a replica in STARTING status
I0324 05:19:12.868939 2761 recover.cpp:564] Updating replica status to VOTING
I0324 05:19:12.890432 2761 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 21.228558ms
I0324 05:19:12.890519 2761 replica.cpp:320] Persisted replica status to VOTING
I0324 05:19:12.890784 2761 recover.cpp:578] Successfully joined the Paxos group
I0324 05:19:12.890976 2761 recover.cpp:462] Recover process terminated
I0324 05:19:12.891670 2761 log.cpp:659] Attempting to start the writer
I0324 05:19:12.893221 2761 replica.cpp:493] Replica received implicit promise request from (15652)@172.17.0.2:43561 with proposal 1
I0324 05:19:12.915550 2761 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.265508ms
I0324 05:19:12.915648 2761 replica.cpp:342] Persisted promised to 1
I0324 05:19:12.917016 2761 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0324 05:19:12.918851 2756 replica.cpp:388] Replica received explicit promise request from (15653)@172.17.0.2:43561 for position 0 with proposal 2
I0324 05:19:12.940664 2756 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 21.729833ms
I0324 05:19:12.940754 2756 replica.cpp:712] Persisted action at 0
I0324 05:19:12.942443 2756 replica.cpp:537] Replica received write request for position 0 from (15654)@172.17.0.2:43561
I0324 05:19:12.942520 2756 leveldb.cpp:436] Reading position from leveldb took 38833ns
I0324 05:19:12.965817 2756 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 23.23781ms
I0324 05:19:12.965910 2756 replica.cpp:712] Persisted action at 0
I0324 05:19:12.967018 2756 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0324 05:19:12.990936 2756 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 23.863534ms
I0324 05:19:12.991024 2756 replica.cpp:712] Persisted action at 0
I0324 05:19:12.991060 2756 replica.cpp:697] Replica learned NOP action at position 0
I0324 05:19:12.993276 2767 log.cpp:675] Writer started with ending position 0
I0324 05:19:12.995275 2761 leveldb.cpp:436] Reading position from leveldb took 82482ns
I0324 05:19:12.997025 2765 registrar.cpp:340] Successfully fetched the registry (0B) in 152.499968ms
I0324 05:19:12.997247 2765 registrar.cpp:439] Applied 1 operations in 63302ns; attempting to update the 'registry'
I0324 05:19:12.998628 2754 log.cpp:683] Attempting to append 170 bytes to the log
I0324 05:19:12.998971 2754 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0324 05:19:13.000890 2761 replica.cpp:537] Replica received write request for position 1 from (15655)@172.17.0.2:43561
I0324 05:19:13.024421 2761 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 23.433889ms
I0324 05:19:13.024555 2761 replica.cpp:712] Persisted action at 1
I0324 05:19:13.025727 2762 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0324 05:19:13.049525 2762 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 23.72295ms
I0324 05:19:13.049634 2762 replica.cpp:712] Persisted action at 1
I0324 05:19:13.049676 2762 replica.cpp:697] Replica learned APPEND action at position 1
I0324 05:19:13.051822 2762 registrar.cpp:484] Successfully updated the 'registry' in 54.48576ms
I0324 05:19:13.051977 2762 registrar.cpp:370] Successfully recovered registrar
I0324 05:19:13.052238 2762 log.cpp:702] Attempting to truncate the log to 1
I0324 05:19:13.052875 2762 master.cpp:1616] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0324 05:19:13.053000 2762 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0324 05:19:13.053526 2762 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0324 05:19:13.054702 2752 replica.cpp:537] Replica received write request for position 2 from (15656)@172.17.0.2:43561
I0324 05:19:13.077133 2752 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 22.361095ms
I0324 05:19:13.077230 2752 replica.cpp:712] Persisted action at 2
I0324 05:19:13.078773 2752 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0324 05:19:13.110591 2752 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 31.765921ms
I0324 05:19:13.110769 2752 leveldb.cpp:399] Deleting ~1 keys from leveldb took 80520ns
I0324 05:19:13.110800 2752 replica.cpp:712] Persisted action at 2
I0324 05:19:13.110837 2752 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0324 05:19:13.115327 2733 scheduler.cpp:172] Version: 0.29.0
I0324 05:19:13.116540 2752 scheduler.cpp:437] New master detected at master@172.17.0.2:43561
I0324 05:19:13.119586 2763 scheduler.cpp:337] Connected with the master at http://172.17.0.2:43561/master/api/v1/scheduler
I0324 05:19:13.121459 2756 scheduler.cpp:230] Sending SUBSCRIBE call to http://172.17.0.2:43561/master/api/v1/scheduler
I0324 05:19:13.122923 2762 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0324 05:19:13.123533 2761 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:51642
I0324 05:19:13.124042 2761 master.cpp:2068] Received subscription request for HTTP framework 'default'
I0324 05:19:13.124119 2761 master.cpp:1845] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0324 05:19:13.124392 2761 master.cpp:2159] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0324 05:19:13.124855 2752 hierarchical.cpp:265] Added framework a946692a-9dc0-435c-8e59-0ba6441f5821-0000
I0324 05:19:13.124953 2752 hierarchical.cpp:1466] No resources available to allocate!
I0324 05:19:13.124990 2752 hierarchical.cpp:1561] No inverse offers to send out!
I0324 05:19:13.125037 2761 master.hpp:1715] Sending heartbeat to a946692a-9dc0-435c-8e59-0ba6441f5821-0000
I0324 05:19:13.125052 2752 hierarchical.cpp:1138] Performed allocation for 0 slaves in 171238ns
I0324 05:19:13.127293 2762 scheduler.cpp:612] Enqueuing event SUBSCRIBED received from http://172.17.0.2:43561/master/api/v1/scheduler
I0324 05:19:13.128273 2762 scheduler.cpp:612] Enqueuing event HEARTBEAT received from http://172.17.0.2:43561/master/api/v1/scheduler
I0324 05:19:13.130108 2767 scheduler.cpp:230] Sending REQUEST call to http://172.17.0.2:43561/master/api/v1/scheduler
I0324 05:19:13.131511 2767 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0324 05:19:13.132060 2767 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:51643
I0324 05:19:13.132369 2767 master.cpp:2765] Processing REQUEST call for framework a946692a-9dc0-435c-8e59-0ba6441f5821-0000 (default)
I0324 05:19:13.132625 2767 hierarchical.cpp:589] Received resource request from framework a946692a-9dc0-435c-8e59-0ba6441f5821-0000
I0324 05:19:13.136173 2755 master.cpp:1251] Framework a946692a-9dc0-435c-8e59-0ba6441f5821-0000 (default) disconnected
I0324 05:19:13.136214 2755 master.cpp:2622] Disconnecting framework a946692a-9dc0-435c-8e59-0ba6441f5821-0000 (default)
I0324 05:19:13.136237 2755 master.cpp:2646] Deactivating framework a946692a-9dc0-435c-8e59-0ba6441f5821-0000 (default)
I0324 05:19:13.136328 2755 master.cpp:1275] Giving framework a946692a-9dc0-435c-8e59-0ba6441f5821-0000 (default) 0ns to failover
I0324 05:19:13.136373 2752 hierarchical.cpp:375] Deactivated framework a946692a-9dc0-435c-8e59-0ba6441f5821-0000
I0324 05:19:13.137739 2752 master.cpp:5340] Framework failover timeout, removing framework a946692a-9dc0-435c-8e59-0ba6441f5821-0000 (default)
I0324 05:19:13.137769 2752 master.cpp:6073] Removing framework a946692a-9dc0-435c-8e59-0ba6441f5821-0000 (default)
I0324 05:19:13.138020 2752 hierarchical.cpp:326] Removed framework a946692a-9dc0-435c-8e59-0ba6441f5821-0000
I0324 05:19:13.147135 2733 master.cpp:1065] Master terminating
[ OK ] ContentType/SchedulerTest.Request/1 (487 ms)
[----------] 26 tests from ContentType/SchedulerTest (20035 ms total)
[----------] Global test environment tear-down
E0324 05:19:13.449739 2733 environment.cpp:778] Failed to remove '/tmp/LogZooKeeperTest_WriteRead_EFf5E5': No such file or directory
E0324 05:19:13.449834 2733 environment.cpp:778] Failed to remove '/tmp/LogZooKeeperTest_LostZooKeeper_8SyJmu': No such file or directory
[==========] 1039 tests from 132 test cases ran. (896937 ms total)
[ PASSED ] 1037 tests.
[ FAILED ] 2 tests, listed below:
[ FAILED ] ProvisionerDockerLocalStoreTest.LocalStoreTestWithTar
[ FAILED ] ProvisionerDockerLocalStoreTest.MetadataManagerInitialization
2 FAILED TESTS
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-1458793396-7920
Untagged: mesos-1458793396-7920:latest
Deleted: 31edc8f7600909646cf23bdab0a94cf0703a80bbb87149538d157e25af27108f
Deleted: c1e0068763d116304328eb599d0350522b3cb02dbbbd68d4b364e6b4e7e42186
Deleted: 9c11eacc04dc8c3843078a6732d0c21f6b21f48cb37bdf64ece34b6422f13b3a
Deleted: af356d4c9ada8e9761943b91b5dd51d747ebec99332247e204d99b0d8ff64bcb
Deleted: 3bc84666340e1d3fc815ffc8d23332c035e28050237cf3f7d89b686560aaa46a
Deleted: 540fbae4af7a0f987f6e66b0542616f4f1b41b4a23f674fc895a7407b6a2a925
Deleted: b1c52c67a6a6be2e3577e306e2532e25a6f8a4da0a4fb2e1bb91352adfe834ad
Deleted: 2ca06caf5aff7e232d29ab4033572193fc64da21bd3b4737d06316d9db9dde04
Deleted: 940f9d1eb7073c5862a623b716126d37559baf8035bb6751b78663c5cdd10b69
Deleted: 5fe470901892c8696e4de7685ad1b465816a89eef15b1a7c67cf1b78acf8ea90
Deleted: 41b100989e42ce55e2f9849e98058b4240ddc54d6ec401aadb37909a2dbc54ba
Deleted: f3f11702420232b6d7a230cad2da4e5adb51fda1c203ee2133beac9cc70ec58c
Deleted: 19bc899e82f37a4d38444be3f1252c351a4f0fac7d4bc5c49cd9e670bcacc091
Deleted: 14861612dcd52d1f8fa957d47572dc4b98f1caac235e44ce80576184ba94d25c
Deleted: 35e03bc9f9344be14b757e183f407ccace9d16c632326e3be8c673f8918ee48f
Deleted: 4b2fd88e47b265f2819afe8fab57ef042b1d6eed87654e02490544102ee27f32
Build step 'Execute shell' marked build as failure