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/01/06 11:43:55 UTC
Build failed in Jenkins: mesos-reviewbot #10611
See <https://builds.apache.org/job/mesos-reviewbot/10611/>
------------------------------------------
[...truncated 167023 lines...]
I0106 10:43:43.161550 31127 status_update_manager.cpp:528] Cleaning up status update stream for task 3779ac68-c861-4a1c-9e19-5af42cc28df1 of framework abfa35b3-0f9e-408b-96ac-cf6409a1b5f1-0000
[ OK ] ContentType/SchedulerTest.Message/1 (848 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I0106 10:43:43.307957 31093 leveldb.cpp:174] Opened db in 140.941029ms
I0106 10:43:43.362377 31093 leveldb.cpp:181] Compacted db in 54.368035ms
I0106 10:43:43.362473 31093 leveldb.cpp:196] Created db iterator in 29854ns
I0106 10:43:43.362496 31093 leveldb.cpp:202] Seeked to beginning of db in 3410ns
I0106 10:43:43.362509 31093 leveldb.cpp:271] Iterated through 0 keys in the db in 347ns
I0106 10:43:43.362567 31093 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0106 10:43:43.363265 31116 recover.cpp:447] Starting replica recovery
I0106 10:43:43.363595 31116 recover.cpp:473] Replica is in EMPTY status
I0106 10:43:43.364835 31126 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (13010)@172.17.0.2:53643
I0106 10:43:43.365087 31126 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0106 10:43:43.365588 31113 recover.cpp:564] Updating replica status to STARTING
I0106 10:43:43.366302 31122 master.cpp:365] Master 2fc319d3-7b73-458f-b40e-97b2611f8be5 (8585bbbadb12) started on 172.17.0.2:53643
I0106 10:43:43.366375 31122 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/hRVRWY/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="25secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.27.0/_inst/share/mesos/webui" --work_dir="/tmp/hRVRWY/master" --zk_session_timeout="10secs"
I0106 10:43:43.366636 31122 master.cpp:414] Master allowing unauthenticated frameworks to register
I0106 10:43:43.366657 31122 master.cpp:417] Master only allowing authenticated slaves to register
I0106 10:43:43.366672 31122 credentials.hpp:35] Loading credentials for authentication from '/tmp/hRVRWY/credentials'
I0106 10:43:43.367058 31122 master.cpp:456] Using default 'crammd5' authenticator
I0106 10:43:43.367231 31122 master.cpp:493] Authorization enabled
I0106 10:43:43.367478 31117 whitelist_watcher.cpp:77] No whitelist given
I0106 10:43:43.367547 31126 hierarchical.cpp:147] Initialized hierarchical allocator process
I0106 10:43:43.369638 31114 master.cpp:1629] The newly elected leader is master@172.17.0.2:53643 with id 2fc319d3-7b73-458f-b40e-97b2611f8be5
I0106 10:43:43.369684 31114 master.cpp:1642] Elected as the leading master!
I0106 10:43:43.369717 31114 master.cpp:1387] Recovering from registrar
I0106 10:43:43.369922 31115 registrar.cpp:307] Recovering registrar
I0106 10:43:43.404000 31118 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 38.237287ms
I0106 10:43:43.404029 31118 replica.cpp:320] Persisted replica status to STARTING
I0106 10:43:43.404330 31113 recover.cpp:473] Replica is in STARTING status
I0106 10:43:43.405242 31127 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (13011)@172.17.0.2:53643
I0106 10:43:43.405712 31112 recover.cpp:193] Received a recover response from a replica in STARTING status
I0106 10:43:43.406219 31127 recover.cpp:564] Updating replica status to VOTING
I0106 10:43:43.445792 31121 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 39.363999ms
I0106 10:43:43.445824 31121 replica.cpp:320] Persisted replica status to VOTING
I0106 10:43:43.445930 31121 recover.cpp:578] Successfully joined the Paxos group
I0106 10:43:43.446209 31121 recover.cpp:462] Recover process terminated
I0106 10:43:43.446689 31114 log.cpp:659] Attempting to start the writer
I0106 10:43:43.448305 31127 replica.cpp:493] Replica received implicit promise request from (13012)@172.17.0.2:53643 with proposal 1
I0106 10:43:43.491240 31127 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 42.887339ms
I0106 10:43:43.491300 31127 replica.cpp:342] Persisted promised to 1
I0106 10:43:43.492097 31115 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0106 10:43:43.493638 31127 replica.cpp:388] Replica received explicit promise request from (13013)@172.17.0.2:53643 for position 0 with proposal 2
I0106 10:43:43.526453 31127 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 32.764792ms
I0106 10:43:43.526490 31127 replica.cpp:712] Persisted action at 0
I0106 10:43:43.528036 31120 replica.cpp:537] Replica received write request for position 0 from (13014)@172.17.0.2:53643
I0106 10:43:43.528152 31120 leveldb.cpp:436] Reading position from leveldb took 57516ns
I0106 10:43:43.551654 31120 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 23.44643ms
I0106 10:43:43.551702 31120 replica.cpp:712] Persisted action at 0
I0106 10:43:43.552579 31123 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0106 10:43:43.593631 31123 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 41.003659ms
I0106 10:43:43.593682 31123 replica.cpp:712] Persisted action at 0
I0106 10:43:43.593709 31123 replica.cpp:697] Replica learned NOP action at position 0
I0106 10:43:43.594702 31124 log.cpp:675] Writer started with ending position 0
I0106 10:43:43.596010 31115 leveldb.cpp:436] Reading position from leveldb took 40652ns
I0106 10:43:43.597105 31114 registrar.cpp:340] Successfully fetched the registry (0B) in 227.092224ms
I0106 10:43:43.597272 31114 registrar.cpp:439] Applied 1 operations in 40314ns; attempting to update the 'registry'
I0106 10:43:43.598148 31125 log.cpp:683] Attempting to append 170 bytes to the log
I0106 10:43:43.598315 31121 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0106 10:43:43.599253 31113 replica.cpp:537] Replica received write request for position 1 from (13015)@172.17.0.2:53643
I0106 10:43:43.619321 31113 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 20.02131ms
I0106 10:43:43.619360 31113 replica.cpp:712] Persisted action at 1
I0106 10:43:43.620481 31125 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0106 10:43:43.645156 31125 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 24.632983ms
I0106 10:43:43.645200 31125 replica.cpp:712] Persisted action at 1
I0106 10:43:43.645222 31125 replica.cpp:697] Replica learned APPEND action at position 1
I0106 10:43:43.646504 31119 registrar.cpp:484] Successfully updated the 'registry' in 49.147136ms
I0106 10:43:43.646695 31119 registrar.cpp:370] Successfully recovered registrar
I0106 10:43:43.646862 31120 log.cpp:702] Attempting to truncate the log to 1
I0106 10:43:43.647095 31114 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0106 10:43:43.647348 31120 hierarchical.cpp:165] Skipping recovery of hierarchical allocator: nothing to recover
I0106 10:43:43.647341 31121 master.cpp:1439] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0106 10:43:43.648244 31126 replica.cpp:537] Replica received write request for position 2 from (13016)@172.17.0.2:53643
I0106 10:43:43.670163 31126 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 21.874392ms
I0106 10:43:43.670202 31126 replica.cpp:712] Persisted action at 2
I0106 10:43:43.670869 31114 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0106 10:43:43.713382 31114 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 42.471918ms
I0106 10:43:43.713469 31114 leveldb.cpp:399] Deleting ~1 keys from leveldb took 43321ns
I0106 10:43:43.713492 31114 replica.cpp:712] Persisted action at 2
I0106 10:43:43.713515 31114 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0106 10:43:43.719223 31093 scheduler.cpp:154] Version: 0.27.0
I0106 10:43:43.719990 31119 scheduler.cpp:236] New master detected at master@172.17.0.2:53643
I0106 10:43:43.721235 31118 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.2:53643
I0106 10:43:43.723548 31122 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0106 10:43:43.724134 31126 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:42567
I0106 10:43:43.724406 31126 master.cpp:1891] Received subscription request for HTTP framework 'default'
I0106 10:43:43.724486 31126 master.cpp:1668] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0106 10:43:43.724834 31126 master.cpp:1982] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0106 10:43:43.725401 31112 hierarchical.cpp:260] Added framework 2fc319d3-7b73-458f-b40e-97b2611f8be5-0000
I0106 10:43:43.725450 31113 master.hpp:1655] Sending heartbeat to 2fc319d3-7b73-458f-b40e-97b2611f8be5-0000
I0106 10:43:43.725491 31112 hierarchical.cpp:1329] No resources available to allocate!
I0106 10:43:43.725538 31112 hierarchical.cpp:1423] No inverse offers to send out!
I0106 10:43:43.725579 31112 hierarchical.cpp:1079] Performed allocation for 0 slaves in 145289ns
I0106 10:43:43.726439 31118 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.2:53643
I0106 10:43:43.727082 31118 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.2:53643
I0106 10:43:43.727810 31120 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0106 10:43:43.728003 31116 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.2:53643
I0106 10:43:43.729931 31124 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0106 10:43:43.730481 31125 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:42568
I0106 10:43:43.730602 31125 master.cpp:2636] Processing REQUEST call for framework 2fc319d3-7b73-458f-b40e-97b2611f8be5-0000 (default)
I0106 10:43:43.730770 31118 hierarchical.cpp:579] Received resource request from framework 2fc319d3-7b73-458f-b40e-97b2611f8be5-0000
I0106 10:43:43.731097 31119 master.cpp:930] Master terminating
I0106 10:43:43.731359 31120 hierarchical.cpp:321] Removed framework 2fc319d3-7b73-458f-b40e-97b2611f8be5-0000
E0106 10:43:43.732600 31120 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (572 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I0106 10:43:43.860353 31093 leveldb.cpp:174] Opened db in 120.924594ms
I0106 10:43:43.896730 31093 leveldb.cpp:181] Compacted db in 36.310746ms
I0106 10:43:43.896812 31093 leveldb.cpp:196] Created db iterator in 25207ns
I0106 10:43:43.896834 31093 leveldb.cpp:202] Seeked to beginning of db in 2467ns
I0106 10:43:43.896847 31093 leveldb.cpp:271] Iterated through 0 keys in the db in 358ns
I0106 10:43:43.896903 31093 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0106 10:43:43.897476 31113 recover.cpp:447] Starting replica recovery
I0106 10:43:43.897905 31127 recover.cpp:473] Replica is in EMPTY status
I0106 10:43:43.899027 31115 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (13024)@172.17.0.2:53643
I0106 10:43:43.899466 31117 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0106 10:43:43.899899 31114 recover.cpp:564] Updating replica status to STARTING
I0106 10:43:43.900877 31120 master.cpp:365] Master 5d77606c-ebfa-40f4-bddc-0b677eac3c60 (8585bbbadb12) started on 172.17.0.2:53643
I0106 10:43:43.900895 31120 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/QvglS2/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="25secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.27.0/_inst/share/mesos/webui" --work_dir="/tmp/QvglS2/master" --zk_session_timeout="10secs"
I0106 10:43:43.901152 31120 master.cpp:414] Master allowing unauthenticated frameworks to register
I0106 10:43:43.901165 31120 master.cpp:417] Master only allowing authenticated slaves to register
I0106 10:43:43.901172 31120 credentials.hpp:35] Loading credentials for authentication from '/tmp/QvglS2/credentials'
I0106 10:43:43.901443 31120 master.cpp:456] Using default 'crammd5' authenticator
I0106 10:43:43.901556 31120 master.cpp:493] Authorization enabled
I0106 10:43:43.901717 31125 hierarchical.cpp:147] Initialized hierarchical allocator process
I0106 10:43:43.901738 31116 whitelist_watcher.cpp:77] No whitelist given
I0106 10:43:43.903121 31127 master.cpp:1629] The newly elected leader is master@172.17.0.2:53643 with id 5d77606c-ebfa-40f4-bddc-0b677eac3c60
I0106 10:43:43.903167 31127 master.cpp:1642] Elected as the leading master!
I0106 10:43:43.903188 31127 master.cpp:1387] Recovering from registrar
I0106 10:43:43.903314 31116 registrar.cpp:307] Recovering registrar
I0106 10:43:43.938496 31119 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 38.436597ms
I0106 10:43:43.938537 31119 replica.cpp:320] Persisted replica status to STARTING
I0106 10:43:43.938772 31119 recover.cpp:473] Replica is in STARTING status
I0106 10:43:43.939666 31118 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (13025)@172.17.0.2:53643
I0106 10:43:43.939972 31117 recover.cpp:193] Received a recover response from a replica in STARTING status
I0106 10:43:43.940335 31115 recover.cpp:564] Updating replica status to VOTING
I0106 10:43:43.980535 31126 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 40.072592ms
I0106 10:43:43.980563 31126 replica.cpp:320] Persisted replica status to VOTING
I0106 10:43:43.980659 31126 recover.cpp:578] Successfully joined the Paxos group
I0106 10:43:43.980849 31126 recover.cpp:462] Recover process terminated
I0106 10:43:43.981219 31126 log.cpp:659] Attempting to start the writer
I0106 10:43:43.982708 31122 replica.cpp:493] Replica received implicit promise request from (13026)@172.17.0.2:53643 with proposal 1
I0106 10:43:44.019383 31122 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 36.630165ms
I0106 10:43:44.019438 31122 replica.cpp:342] Persisted promised to 1
I0106 10:43:44.020231 31115 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0106 10:43:44.021898 31120 replica.cpp:388] Replica received explicit promise request from (13027)@172.17.0.2:53643 for position 0 with proposal 2
I0106 10:43:44.069697 31120 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 47.677832ms
I0106 10:43:44.069753 31120 replica.cpp:712] Persisted action at 0
I0106 10:43:44.070906 31121 replica.cpp:537] Replica received write request for position 0 from (13028)@172.17.0.2:53643
I0106 10:43:44.070971 31121 leveldb.cpp:436] Reading position from leveldb took 30289ns
I0106 10:43:44.136868 31121 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 65.847514ms
I0106 10:43:44.136935 31121 replica.cpp:712] Persisted action at 0
I0106 10:43:44.137817 31114 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0106 10:43:44.229065 31114 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 91.206388ms
I0106 10:43:44.229154 31114 replica.cpp:712] Persisted action at 0
I0106 10:43:44.229200 31114 replica.cpp:697] Replica learned NOP action at position 0
I0106 10:43:44.230048 31125 log.cpp:675] Writer started with ending position 0
I0106 10:43:44.231185 31117 leveldb.cpp:436] Reading position from leveldb took 54015ns
I0106 10:43:44.232055 31121 registrar.cpp:340] Successfully fetched the registry (0B) in 328.696064ms
I0106 10:43:44.232177 31121 registrar.cpp:439] Applied 1 operations in 32756ns; attempting to update the 'registry'
I0106 10:43:44.232903 31112 log.cpp:683] Attempting to append 170 bytes to the log
I0106 10:43:44.233032 31118 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0106 10:43:44.233767 31125 replica.cpp:537] Replica received write request for position 1 from (13029)@172.17.0.2:53643
I0106 10:43:44.303524 31125 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 69.695605ms
I0106 10:43:44.303599 31125 replica.cpp:712] Persisted action at 1
I0106 10:43:44.304733 31114 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0106 10:43:44.356675 31114 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 51.898251ms
I0106 10:43:44.356725 31114 replica.cpp:712] Persisted action at 1
I0106 10:43:44.356755 31114 replica.cpp:697] Replica learned APPEND action at position 1
I0106 10:43:44.358065 31118 registrar.cpp:484] Successfully updated the 'registry' in 125.829888ms
I0106 10:43:44.358263 31118 registrar.cpp:370] Successfully recovered registrar
I0106 10:43:44.358346 31114 log.cpp:702] Attempting to truncate the log to 1
I0106 10:43:44.358499 31117 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0106 10:43:44.358945 31112 master.cpp:1439] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0106 10:43:44.358979 31115 hierarchical.cpp:165] Skipping recovery of hierarchical allocator: nothing to recover
I0106 10:43:44.359488 31122 replica.cpp:537] Replica received write request for position 2 from (13030)@172.17.0.2:53643
I0106 10:43:44.404677 31122 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 45.142002ms
I0106 10:43:44.404727 31122 replica.cpp:712] Persisted action at 2
I0106 10:43:44.405608 31118 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0106 10:43:44.446302 31118 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 40.66014ms
I0106 10:43:44.446387 31118 leveldb.cpp:399] Deleting ~1 keys from leveldb took 42896ns
I0106 10:43:44.446410 31118 replica.cpp:712] Persisted action at 2
I0106 10:43:44.446434 31118 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0106 10:43:44.450676 31093 scheduler.cpp:154] Version: 0.27.0
I0106 10:43:44.451573 31125 scheduler.cpp:236] New master detected at master@172.17.0.2:53643
I0106 10:43:44.452836 31120 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.2:53643
I0106 10:43:44.455318 31118 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0106 10:43:44.455910 31127 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:42571
I0106 10:43:44.456398 31127 master.cpp:1891] Received subscription request for HTTP framework 'default'
I0106 10:43:44.456500 31127 master.cpp:1668] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0106 10:43:44.456921 31127 master.cpp:1982] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0106 10:43:44.457412 31119 hierarchical.cpp:260] Added framework 5d77606c-ebfa-40f4-bddc-0b677eac3c60-0000
I0106 10:43:44.457504 31119 hierarchical.cpp:1329] No resources available to allocate!
I0106 10:43:44.457537 31123 master.hpp:1655] Sending heartbeat to 5d77606c-ebfa-40f4-bddc-0b677eac3c60-0000
I0106 10:43:44.457558 31119 hierarchical.cpp:1423] No inverse offers to send out!
I0106 10:43:44.457597 31119 hierarchical.cpp:1079] Performed allocation for 0 slaves in 146411ns
I0106 10:43:44.458482 31112 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.2:53643
I0106 10:43:44.459077 31112 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.2:53643
I0106 10:43:44.459936 31122 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0106 10:43:44.459987 31112 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.2:53643
I0106 10:43:44.461940 31125 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0106 10:43:44.462398 31119 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:42572
I0106 10:43:44.462657 31119 master.cpp:2636] Processing REQUEST call for framework 5d77606c-ebfa-40f4-bddc-0b677eac3c60-0000 (default)
I0106 10:43:44.462846 31120 hierarchical.cpp:579] Received resource request from framework 5d77606c-ebfa-40f4-bddc-0b677eac3c60-0000
I0106 10:43:44.463163 31120 master.cpp:930] Master terminating
I0106 10:43:44.463399 31121 hierarchical.cpp:321] Removed framework 5d77606c-ebfa-40f4-bddc-0b677eac3c60-0000
E0106 10:43:44.464844 31115 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (733 ms)
[----------] 22 tests from ContentType/SchedulerTest (17643 ms total)
[----------] Global test environment tear-down
[==========] 922 tests from 120 test cases ran. (743556 ms total)
[ PASSED ] 921 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] DiskUsageCollectorTest.File
1 FAILED TEST
YOU HAVE 6 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1452074442-15777
Untagged: mesos-1452074442-15777:latest
Deleted: 39636bc9ec141b7ecf1da4c6af6e4eb27b4fc1a9c490787de4bac1e8fc9ef43d
Deleted: fb23e3912e3b4d23630240b1d83c7af3c3742943bff7a966434290ba580a71cf
Deleted: 823e34edaad9c85d5dddbc6f79ecb98094883b361706b5d8e81e0dadac8e057f
Deleted: f9190d7ac45cb16d82f0c5f6bcae5bcf60bc5e2fd25359b1d83bba86ea657421
Deleted: f21b36b61a1b2e807271cbc24be71e074fd5767806a8d6fb7eec564d96acf86c
Deleted: 5837270cd11f94dfa34c17c74afe419cd93bc30e9a84bfdb2502340a4bd5bced
Deleted: bd70fbeba32e6997bfdcfd7f0fc2604d264ac78fe4f12a19f4057126da906d86
Deleted: 1bb34112588280768771566677da2398f1cff8448b367760712cb44f5f03ad60
Deleted: ff38d0c0e262d8b0b1d4ab8a9cf4c2f16e9fc7b9e01e6f9a66aa067613c40768
Deleted: d267f4799e567b81fb659f1f0be3d5a3096c0ab141db5b5b02354d2306c947d6
Deleted: 99ed0a3c00b52d5bbad2fee5898d74c34f11490cfca226e1df775e1875c70329
Deleted: 8a6aa6ba7d3a9b7b3fb1a97db2751e8e89ad3c397b5971375773505a6ba21904
Deleted: c8d535f81c4a6930c85b09b524103b9b85610a290dfe76f7811f3d83dce264c5
Error handling URL https://reviews.apache.org/api/review-requests/41963/reviews/: INTERNAL SERVER ERROR
git clean -fd
git reset --hard 9aa261ee0775f5ddc846ed4814f897298b2dd7aa
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : mesos-reviewbot #10613
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/mesos-reviewbot/10613/>
Build failed in Jenkins: mesos-reviewbot #10612
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/mesos-reviewbot/10612/>
------------------------------------------
[...truncated 166925 lines...]
I0106 11:49:31.961521 31129 status_update_manager.cpp:528] Cleaning up status update stream for task ec19543d-fce0-4883-97b0-ee90ca342729 of framework 7630f02c-f5ee-437d-9e68-49c09832ad00-0000
[ OK ] ContentType/SchedulerTest.Message/1 (674 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I0106 11:49:32.066715 31101 leveldb.cpp:174] Opened db in 99.212871ms
I0106 11:49:32.113814 31101 leveldb.cpp:181] Compacted db in 47.057289ms
I0106 11:49:32.113884 31101 leveldb.cpp:196] Created db iterator in 23184ns
I0106 11:49:32.113905 31101 leveldb.cpp:202] Seeked to beginning of db in 2235ns
I0106 11:49:32.113917 31101 leveldb.cpp:271] Iterated through 0 keys in the db in 315ns
I0106 11:49:32.113963 31101 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0106 11:49:32.114408 31124 recover.cpp:447] Starting replica recovery
I0106 11:49:32.114750 31124 recover.cpp:473] Replica is in EMPTY status
I0106 11:49:32.116256 31130 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (13018)@172.17.0.5:55191
I0106 11:49:32.116811 31121 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0106 11:49:32.117436 31130 recover.cpp:564] Updating replica status to STARTING
I0106 11:49:32.118254 31132 master.cpp:365] Master fbb61333-e8d6-42c6-aac8-75b8df84bea9 (0ab413ce0505) started on 172.17.0.5:55191
I0106 11:49:32.118280 31132 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/pgsBuQ/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="25secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.27.0/_inst/share/mesos/webui" --work_dir="/tmp/pgsBuQ/master" --zk_session_timeout="10secs"
I0106 11:49:32.118656 31132 master.cpp:414] Master allowing unauthenticated frameworks to register
I0106 11:49:32.118675 31132 master.cpp:417] Master only allowing authenticated slaves to register
I0106 11:49:32.118692 31132 credentials.hpp:35] Loading credentials for authentication from '/tmp/pgsBuQ/credentials'
I0106 11:49:32.119021 31132 master.cpp:456] Using default 'crammd5' authenticator
I0106 11:49:32.119168 31132 master.cpp:493] Authorization enabled
I0106 11:49:32.119349 31133 whitelist_watcher.cpp:77] No whitelist given
I0106 11:49:32.119371 31130 hierarchical.cpp:147] Initialized hierarchical allocator process
I0106 11:49:32.121203 31135 master.cpp:1629] The newly elected leader is master@172.17.0.5:55191 with id fbb61333-e8d6-42c6-aac8-75b8df84bea9
I0106 11:49:32.121248 31135 master.cpp:1642] Elected as the leading master!
I0106 11:49:32.121270 31135 master.cpp:1387] Recovering from registrar
I0106 11:49:32.121453 31122 registrar.cpp:307] Recovering registrar
I0106 11:49:32.147500 31127 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 29.825105ms
I0106 11:49:32.147538 31127 replica.cpp:320] Persisted replica status to STARTING
I0106 11:49:32.147867 31125 recover.cpp:473] Replica is in STARTING status
I0106 11:49:32.149124 31124 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (13019)@172.17.0.5:55191
I0106 11:49:32.149726 31122 recover.cpp:193] Received a recover response from a replica in STARTING status
I0106 11:49:32.150243 31133 recover.cpp:564] Updating replica status to VOTING
I0106 11:49:32.189343 31135 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 38.918742ms
I0106 11:49:32.189388 31135 replica.cpp:320] Persisted replica status to VOTING
I0106 11:49:32.189504 31120 recover.cpp:578] Successfully joined the Paxos group
I0106 11:49:32.189796 31120 recover.cpp:462] Recover process terminated
I0106 11:49:32.190414 31122 log.cpp:659] Attempting to start the writer
I0106 11:49:32.191787 31120 replica.cpp:493] Replica received implicit promise request from (13020)@172.17.0.5:55191 with proposal 1
I0106 11:49:32.230996 31120 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 39.170168ms
I0106 11:49:32.231034 31120 replica.cpp:342] Persisted promised to 1
I0106 11:49:32.231724 31135 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0106 11:49:32.233234 31130 replica.cpp:388] Replica received explicit promise request from (13021)@172.17.0.5:55191 for position 0 with proposal 2
I0106 11:49:32.270505 31130 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 37.222099ms
I0106 11:49:32.270545 31130 replica.cpp:712] Persisted action at 0
I0106 11:49:32.271836 31126 replica.cpp:537] Replica received write request for position 0 from (13022)@172.17.0.5:55191
I0106 11:49:32.271914 31126 leveldb.cpp:436] Reading position from leveldb took 32459ns
I0106 11:49:32.314714 31126 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 42.737422ms
I0106 11:49:32.314761 31126 replica.cpp:712] Persisted action at 0
I0106 11:49:32.315487 31135 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0106 11:49:32.354032 31135 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 38.501805ms
I0106 11:49:32.354071 31135 replica.cpp:712] Persisted action at 0
I0106 11:49:32.354094 31135 replica.cpp:697] Replica learned NOP action at position 0
I0106 11:49:32.354969 31134 log.cpp:675] Writer started with ending position 0
I0106 11:49:32.356129 31129 leveldb.cpp:436] Reading position from leveldb took 34221ns
I0106 11:49:32.357199 31134 registrar.cpp:340] Successfully fetched the registry (0B) in 235.611904ms
I0106 11:49:32.357317 31134 registrar.cpp:439] Applied 1 operations in 27162ns; attempting to update the 'registry'
I0106 11:49:32.358175 31127 log.cpp:683] Attempting to append 170 bytes to the log
I0106 11:49:32.358325 31135 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0106 11:49:32.359212 31126 replica.cpp:537] Replica received write request for position 1 from (13023)@172.17.0.5:55191
I0106 11:49:32.398217 31126 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 38.957827ms
I0106 11:49:32.398264 31126 replica.cpp:712] Persisted action at 1
I0106 11:49:32.399070 31130 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0106 11:49:32.437594 31130 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 38.486545ms
I0106 11:49:32.437639 31130 replica.cpp:712] Persisted action at 1
I0106 11:49:32.437656 31130 replica.cpp:697] Replica learned APPEND action at position 1
I0106 11:49:32.439271 31134 registrar.cpp:484] Successfully updated the 'registry' in 81.883136ms
I0106 11:49:32.439429 31134 registrar.cpp:370] Successfully recovered registrar
I0106 11:49:32.439584 31129 log.cpp:702] Attempting to truncate the log to 1
I0106 11:49:32.439769 31121 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0106 11:49:32.440220 31135 master.cpp:1439] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0106 11:49:32.440372 31133 hierarchical.cpp:165] Skipping recovery of hierarchical allocator: nothing to recover
I0106 11:49:32.440969 31120 replica.cpp:537] Replica received write request for position 2 from (13024)@172.17.0.5:55191
I0106 11:49:32.479554 31120 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 38.535859ms
I0106 11:49:32.479693 31120 replica.cpp:712] Persisted action at 2
I0106 11:49:32.480484 31124 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0106 11:49:32.521267 31124 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 40.746024ms
I0106 11:49:32.521363 31124 leveldb.cpp:399] Deleting ~1 keys from leveldb took 47369ns
I0106 11:49:32.521396 31124 replica.cpp:712] Persisted action at 2
I0106 11:49:32.521420 31124 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0106 11:49:32.531302 31101 scheduler.cpp:154] Version: 0.27.0
I0106 11:49:32.532192 31126 scheduler.cpp:236] New master detected at master@172.17.0.5:55191
I0106 11:49:32.533471 31135 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.5:55191
I0106 11:49:32.535317 31131 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0106 11:49:32.535866 31120 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.5:46597
I0106 11:49:32.536099 31120 master.cpp:1891] Received subscription request for HTTP framework 'default'
I0106 11:49:32.536169 31120 master.cpp:1668] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0106 11:49:32.536481 31120 master.cpp:1982] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0106 11:49:32.536983 31125 hierarchical.cpp:260] Added framework fbb61333-e8d6-42c6-aac8-75b8df84bea9-0000
I0106 11:49:32.537058 31134 master.hpp:1655] Sending heartbeat to fbb61333-e8d6-42c6-aac8-75b8df84bea9-0000
I0106 11:49:32.537058 31125 hierarchical.cpp:1329] No resources available to allocate!
I0106 11:49:32.537207 31125 hierarchical.cpp:1423] No inverse offers to send out!
I0106 11:49:32.537255 31125 hierarchical.cpp:1079] Performed allocation for 0 slaves in 237969ns
I0106 11:49:32.537966 31131 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.5:55191
I0106 11:49:32.538493 31131 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.5:55191
I0106 11:49:32.539273 31123 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0106 11:49:32.539448 31130 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.5:55191
I0106 11:49:32.541147 31129 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0106 11:49:32.541664 31126 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.5:46598
I0106 11:49:32.541781 31126 master.cpp:2636] Processing REQUEST call for framework fbb61333-e8d6-42c6-aac8-75b8df84bea9-0000 (default)
I0106 11:49:32.542021 31125 hierarchical.cpp:579] Received resource request from framework fbb61333-e8d6-42c6-aac8-75b8df84bea9-0000
I0106 11:49:32.542800 31121 master.cpp:930] Master terminating
I0106 11:49:32.543094 31123 hierarchical.cpp:321] Removed framework fbb61333-e8d6-42c6-aac8-75b8df84bea9-0000
E0106 11:49:32.544196 31120 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (584 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I0106 11:49:32.672826 31101 leveldb.cpp:174] Opened db in 121.915954ms
I0106 11:49:32.727296 31101 leveldb.cpp:181] Compacted db in 54.428083ms
I0106 11:49:32.727362 31101 leveldb.cpp:196] Created db iterator in 19475ns
I0106 11:49:32.727385 31101 leveldb.cpp:202] Seeked to beginning of db in 2153ns
I0106 11:49:32.727396 31101 leveldb.cpp:271] Iterated through 0 keys in the db in 270ns
I0106 11:49:32.727432 31101 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0106 11:49:32.727988 31135 recover.cpp:447] Starting replica recovery
I0106 11:49:32.728318 31135 recover.cpp:473] Replica is in EMPTY status
I0106 11:49:32.729423 31133 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (13032)@172.17.0.5:55191
I0106 11:49:32.729912 31132 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0106 11:49:32.730486 31121 recover.cpp:564] Updating replica status to STARTING
I0106 11:49:32.731446 31133 master.cpp:365] Master ecc3a039-b865-4ae6-acf8-ec954be4e442 (0ab413ce0505) started on 172.17.0.5:55191
I0106 11:49:32.731477 31133 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/iziw5r/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="25secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.27.0/_inst/share/mesos/webui" --work_dir="/tmp/iziw5r/master" --zk_session_timeout="10secs"
I0106 11:49:32.731834 31133 master.cpp:414] Master allowing unauthenticated frameworks to register
I0106 11:49:32.731848 31133 master.cpp:417] Master only allowing authenticated slaves to register
I0106 11:49:32.731856 31133 credentials.hpp:35] Loading credentials for authentication from '/tmp/iziw5r/credentials'
I0106 11:49:32.732177 31133 master.cpp:456] Using default 'crammd5' authenticator
I0106 11:49:32.732311 31133 master.cpp:493] Authorization enabled
I0106 11:49:32.732472 31135 whitelist_watcher.cpp:77] No whitelist given
I0106 11:49:32.732481 31124 hierarchical.cpp:147] Initialized hierarchical allocator process
I0106 11:49:32.734379 31120 master.cpp:1629] The newly elected leader is master@172.17.0.5:55191 with id ecc3a039-b865-4ae6-acf8-ec954be4e442
I0106 11:49:32.734419 31120 master.cpp:1642] Elected as the leading master!
I0106 11:49:32.734443 31120 master.cpp:1387] Recovering from registrar
I0106 11:49:32.734583 31130 registrar.cpp:307] Recovering registrar
I0106 11:49:32.756362 31129 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 25.680118ms
I0106 11:49:32.756399 31129 replica.cpp:320] Persisted replica status to STARTING
I0106 11:49:32.756660 31129 recover.cpp:473] Replica is in STARTING status
I0106 11:49:32.757661 31128 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (13033)@172.17.0.5:55191
I0106 11:49:32.758072 31121 recover.cpp:193] Received a recover response from a replica in STARTING status
I0106 11:49:32.758560 31126 recover.cpp:564] Updating replica status to VOTING
I0106 11:49:32.781445 31129 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.69681ms
I0106 11:49:32.781483 31129 replica.cpp:320] Persisted replica status to VOTING
I0106 11:49:32.781647 31134 recover.cpp:578] Successfully joined the Paxos group
I0106 11:49:32.781914 31134 recover.cpp:462] Recover process terminated
I0106 11:49:32.782403 31127 log.cpp:659] Attempting to start the writer
I0106 11:49:32.783516 31131 replica.cpp:493] Replica received implicit promise request from (13034)@172.17.0.5:55191 with proposal 1
I0106 11:49:32.806517 31131 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.967082ms
I0106 11:49:32.806545 31131 replica.cpp:342] Persisted promised to 1
I0106 11:49:32.807096 31135 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0106 11:49:32.808318 31125 replica.cpp:388] Replica received explicit promise request from (13035)@172.17.0.5:55191 for position 0 with proposal 2
I0106 11:49:32.831693 31125 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 23.319345ms
I0106 11:49:32.831732 31125 replica.cpp:712] Persisted action at 0
I0106 11:49:32.832959 31135 replica.cpp:537] Replica received write request for position 0 from (13036)@172.17.0.5:55191
I0106 11:49:32.833024 31135 leveldb.cpp:436] Reading position from leveldb took 28092ns
I0106 11:49:32.856955 31135 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 23.883569ms
I0106 11:49:32.857000 31135 replica.cpp:712] Persisted action at 0
I0106 11:49:32.857851 31131 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0106 11:49:32.881925 31131 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 24.030723ms
I0106 11:49:32.881968 31131 replica.cpp:712] Persisted action at 0
I0106 11:49:32.881999 31131 replica.cpp:697] Replica learned NOP action at position 0
I0106 11:49:32.882783 31133 log.cpp:675] Writer started with ending position 0
I0106 11:49:32.883944 31128 leveldb.cpp:436] Reading position from leveldb took 31334ns
I0106 11:49:32.884958 31130 registrar.cpp:340] Successfully fetched the registry (0B) in 150.303744ms
I0106 11:49:32.885100 31130 registrar.cpp:439] Applied 1 operations in 28574ns; attempting to update the 'registry'
I0106 11:49:32.885941 31135 log.cpp:683] Attempting to append 170 bytes to the log
I0106 11:49:32.886088 31124 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0106 11:49:32.886963 31123 replica.cpp:537] Replica received write request for position 1 from (13037)@172.17.0.5:55191
I0106 11:49:32.924118 31123 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 37.109382ms
I0106 11:49:32.924157 31123 replica.cpp:712] Persisted action at 1
I0106 11:49:32.924940 31131 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0106 11:49:32.966116 31131 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 41.132329ms
I0106 11:49:32.966163 31131 replica.cpp:712] Persisted action at 1
I0106 11:49:32.966189 31131 replica.cpp:697] Replica learned APPEND action at position 1
I0106 11:49:32.967284 31130 registrar.cpp:484] Successfully updated the 'registry' in 82.10304ms
I0106 11:49:32.967445 31130 registrar.cpp:370] Successfully recovered registrar
I0106 11:49:32.967538 31134 log.cpp:702] Attempting to truncate the log to 1
I0106 11:49:32.967721 31124 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0106 11:49:32.968111 31123 master.cpp:1439] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0106 11:49:32.968165 31134 hierarchical.cpp:165] Skipping recovery of hierarchical allocator: nothing to recover
I0106 11:49:32.968797 31127 replica.cpp:537] Replica received write request for position 2 from (13038)@172.17.0.5:55191
I0106 11:49:33.008378 31127 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 39.529076ms
I0106 11:49:33.008417 31127 replica.cpp:712] Persisted action at 2
I0106 11:49:33.009238 31134 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0106 11:49:33.047308 31134 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 38.028243ms
I0106 11:49:33.047385 31134 leveldb.cpp:399] Deleting ~1 keys from leveldb took 38824ns
I0106 11:49:33.047416 31134 replica.cpp:712] Persisted action at 2
I0106 11:49:33.047449 31134 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0106 11:49:33.049212 31101 scheduler.cpp:154] Version: 0.27.0
I0106 11:49:33.049991 31129 scheduler.cpp:236] New master detected at master@172.17.0.5:55191
I0106 11:49:33.051322 31126 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.5:55191
I0106 11:49:33.053309 31120 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0106 11:49:33.053957 31132 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.5:46599
I0106 11:49:33.054332 31132 master.cpp:1891] Received subscription request for HTTP framework 'default'
I0106 11:49:33.054399 31132 master.cpp:1668] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0106 11:49:33.054716 31132 master.cpp:1982] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0106 11:49:33.055189 31128 hierarchical.cpp:260] Added framework ecc3a039-b865-4ae6-acf8-ec954be4e442-0000
I0106 11:49:33.055256 31128 hierarchical.cpp:1329] No resources available to allocate!
I0106 11:49:33.055347 31125 master.hpp:1655] Sending heartbeat to ecc3a039-b865-4ae6-acf8-ec954be4e442-0000
I0106 11:49:33.055531 31128 hierarchical.cpp:1423] No inverse offers to send out!
I0106 11:49:33.055577 31128 hierarchical.cpp:1079] Performed allocation for 0 slaves in 355418ns
I0106 11:49:33.056354 31122 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.5:55191
I0106 11:49:33.056890 31122 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.5:55191
I0106 11:49:33.057786 31135 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0106 11:49:33.057817 31122 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.5:55191
I0106 11:49:33.059535 31133 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0106 11:49:33.059979 31130 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.5:46600
I0106 11:49:33.060245 31130 master.cpp:2636] Processing REQUEST call for framework ecc3a039-b865-4ae6-acf8-ec954be4e442-0000 (default)
I0106 11:49:33.060508 31123 hierarchical.cpp:579] Received resource request from framework ecc3a039-b865-4ae6-acf8-ec954be4e442-0000
I0106 11:49:33.060961 31121 master.cpp:930] Master terminating
I0106 11:49:33.061163 31128 hierarchical.cpp:321] Removed framework ecc3a039-b865-4ae6-acf8-ec954be4e442-0000
E0106 11:49:33.062439 31134 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (518 ms)
[----------] 22 tests from ContentType/SchedulerTest (15327 ms total)
[----------] Global test environment tear-down
[==========] 922 tests from 120 test cases ran. (718539 ms total)
[ PASSED ] 921 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] DiskUsageCollectorTest.File
1 FAILED TEST
YOU HAVE 6 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1452078658-20735
Untagged: mesos-1452078658-20735:latest
Deleted: 3635dacd6ea0dbab750dc2600c2db45171d5d92a09ba1dfaf5c5f0773ec13f4b
Deleted: 3c64501e6c7334aa8a9358e7a7316165aafdda85a927c1d76465c09e91236429
Deleted: da751af4df7557fd51dceae172181f6236f24aa22934bd95ec9d4dbee3ce6277
Deleted: 13f16919c0b1cd84baf7fdcdaa1f6ef1c208df456c4c68fe801869fbf6177d62
Deleted: 37055392fd5fae408d56569314c0ca65f8c432d2bc28f3241ad114423737bbb5
Deleted: 7abc9d639775291a45fbf22c57b8303821648c6c12c9a39a4157d838daf3aaa5
Deleted: 6ac7600c7c0c21ac9e3afaa5845f8d89a29c6ec4ec84bedddc95f5b685f03165
Deleted: 16a071b3a5b328a54fe2eba4d4df9bb0278f1e6e22a05112ac2566a72904bd7a
Deleted: 2e8a5fbf67bf925e4ed5c4a57abdc0f48af84c4a06be232aa167c2592b99c983
Deleted: 9dfbc2cd0a2d8f0185968d30a0178c06171899f926182dace47a6e56598c47ee
Deleted: 516c1775745b3063578af0393c0328dfe8b075d72b581078b4ec22a10e9e2c6b
Deleted: 709fef89e4523da5bf4e41046f4c86c35aad9ce460816ef26355afb4ee72cabb
Deleted: f4ccbcadda532421d28cea37886ce0c0ea5858aa4b7e08e2cdcb5b89ea7f8276
Error handling URL https://reviews.apache.org/api/review-requests/41963/reviews/: INTERNAL SERVER ERROR
git clean -fd
git reset --hard 9aa261ee0775f5ddc846ed4814f897298b2dd7aa
Build step 'Execute shell' marked build as failure