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/07 04:34:50 UTC
Build failed in Jenkins: mesos-reviewbot #10629
See <https://builds.apache.org/job/mesos-reviewbot/10629/>
------------------------------------------
[...truncated 167413 lines...]
[ OK ] ContentType/SchedulerTest.Message/1 (681 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I0107 03:34:32.713248 31096 leveldb.cpp:174] Opened db in 124.406175ms
I0107 03:34:32.755214 31096 leveldb.cpp:181] Compacted db in 41.894465ms
I0107 03:34:32.755297 31096 leveldb.cpp:196] Created db iterator in 26724ns
I0107 03:34:32.755333 31096 leveldb.cpp:202] Seeked to beginning of db in 2482ns
I0107 03:34:32.755359 31096 leveldb.cpp:271] Iterated through 0 keys in the db in 482ns
I0107 03:34:32.755427 31096 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0107 03:34:32.757218 31118 recover.cpp:447] Starting replica recovery
I0107 03:34:32.757494 31118 recover.cpp:473] Replica is in EMPTY status
I0107 03:34:32.758687 31123 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (12966)@172.17.0.5:44990
I0107 03:34:32.759098 31116 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0107 03:34:32.759542 31129 recover.cpp:564] Updating replica status to STARTING
I0107 03:34:32.759969 31118 master.cpp:365] Master 7121910a-a4b4-41e2-a6be-eee549ccd203 (d5186ce46002) started on 172.17.0.5:44990
I0107 03:34:32.759992 31118 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/SAxpSj/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/SAxpSj/master" --zk_session_timeout="10secs"
I0107 03:34:32.760285 31118 master.cpp:414] Master allowing unauthenticated frameworks to register
I0107 03:34:32.760296 31118 master.cpp:417] Master only allowing authenticated slaves to register
I0107 03:34:32.760301 31118 credentials.hpp:35] Loading credentials for authentication from '/tmp/SAxpSj/credentials'
I0107 03:34:32.760609 31118 master.cpp:456] Using default 'crammd5' authenticator
I0107 03:34:32.760740 31118 master.cpp:493] Authorization enabled
I0107 03:34:32.760893 31123 whitelist_watcher.cpp:77] No whitelist given
I0107 03:34:32.760933 31120 hierarchical.cpp:147] Initialized hierarchical allocator process
I0107 03:34:32.762712 31121 master.cpp:1629] The newly elected leader is master@172.17.0.5:44990 with id 7121910a-a4b4-41e2-a6be-eee549ccd203
I0107 03:34:32.762751 31121 master.cpp:1642] Elected as the leading master!
I0107 03:34:32.762773 31121 master.cpp:1387] Recovering from registrar
I0107 03:34:32.763023 31128 registrar.cpp:307] Recovering registrar
I0107 03:34:32.788800 31119 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 29.049583ms
I0107 03:34:32.788885 31119 replica.cpp:320] Persisted replica status to STARTING
I0107 03:34:32.789177 31118 recover.cpp:473] Replica is in STARTING status
I0107 03:34:32.790303 31125 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (12967)@172.17.0.5:44990
I0107 03:34:32.790822 31130 recover.cpp:193] Received a recover response from a replica in STARTING status
I0107 03:34:32.791333 31123 recover.cpp:564] Updating replica status to VOTING
I0107 03:34:32.831470 31126 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 39.890523ms
I0107 03:34:32.831547 31126 replica.cpp:320] Persisted replica status to VOTING
I0107 03:34:32.831814 31125 recover.cpp:578] Successfully joined the Paxos group
I0107 03:34:32.832116 31125 recover.cpp:462] Recover process terminated
I0107 03:34:32.832819 31124 log.cpp:659] Attempting to start the writer
I0107 03:34:32.834249 31115 replica.cpp:493] Replica received implicit promise request from (12968)@172.17.0.5:44990 with proposal 1
I0107 03:34:32.864806 31115 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.514999ms
I0107 03:34:32.864853 31115 replica.cpp:342] Persisted promised to 1
I0107 03:34:32.865563 31128 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0107 03:34:32.867102 31128 replica.cpp:388] Replica received explicit promise request from (12969)@172.17.0.5:44990 for position 0 with proposal 2
I0107 03:34:32.898284 31128 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 31.133943ms
I0107 03:34:32.898331 31128 replica.cpp:712] Persisted action at 0
I0107 03:34:32.899667 31116 replica.cpp:537] Replica received write request for position 0 from (12970)@172.17.0.5:44990
I0107 03:34:32.899739 31116 leveldb.cpp:436] Reading position from leveldb took 32293ns
I0107 03:34:32.937809 31116 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 38.012222ms
I0107 03:34:32.937862 31116 replica.cpp:712] Persisted action at 0
I0107 03:34:32.938652 31118 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0107 03:34:32.964025 31118 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 25.329223ms
I0107 03:34:32.964083 31118 replica.cpp:712] Persisted action at 0
I0107 03:34:32.964112 31118 replica.cpp:697] Replica learned NOP action at position 0
I0107 03:34:32.964751 31127 log.cpp:675] Writer started with ending position 0
I0107 03:34:32.965886 31125 leveldb.cpp:436] Reading position from leveldb took 43620ns
I0107 03:34:32.966898 31126 registrar.cpp:340] Successfully fetched the registry (0B) in 203.819776ms
I0107 03:34:32.967021 31126 registrar.cpp:439] Applied 1 operations in 33037ns; attempting to update the 'registry'
I0107 03:34:32.967705 31115 log.cpp:683] Attempting to append 170 bytes to the log
I0107 03:34:32.967825 31130 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0107 03:34:32.969331 31122 replica.cpp:537] Replica received write request for position 1 from (12971)@172.17.0.5:44990
I0107 03:34:33.003957 31122 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 34.529678ms
I0107 03:34:33.004024 31122 replica.cpp:712] Persisted action at 1
I0107 03:34:33.004828 31130 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0107 03:34:33.033941 31130 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 29.060885ms
I0107 03:34:33.034018 31130 replica.cpp:712] Persisted action at 1
I0107 03:34:33.034049 31130 replica.cpp:697] Replica learned APPEND action at position 1
I0107 03:34:33.035576 31124 registrar.cpp:484] Successfully updated the 'registry' in 68.436992ms
I0107 03:34:33.035801 31124 registrar.cpp:370] Successfully recovered registrar
I0107 03:34:33.035869 31127 log.cpp:702] Attempting to truncate the log to 1
I0107 03:34:33.036360 31121 master.cpp:1439] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0107 03:34:33.036383 31125 hierarchical.cpp:165] Skipping recovery of hierarchical allocator: nothing to recover
I0107 03:34:33.035995 31122 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0107 03:34:33.037490 31119 replica.cpp:537] Replica received write request for position 2 from (12972)@172.17.0.5:44990
I0107 03:34:33.081989 31119 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 44.428195ms
I0107 03:34:33.082077 31119 replica.cpp:712] Persisted action at 2
I0107 03:34:33.083534 31119 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0107 03:34:33.116415 31119 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 32.828843ms
I0107 03:34:33.116551 31119 leveldb.cpp:399] Deleting ~1 keys from leveldb took 66002ns
I0107 03:34:33.116574 31119 replica.cpp:712] Persisted action at 2
I0107 03:34:33.116618 31119 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0107 03:34:33.128180 31096 scheduler.cpp:154] Version: 0.27.0
I0107 03:34:33.128978 31119 scheduler.cpp:236] New master detected at master@172.17.0.5:44990
I0107 03:34:33.130511 31122 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.5:44990
I0107 03:34:33.132763 31128 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0107 03:34:33.133255 31117 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.5:45654
I0107 03:34:33.133436 31117 master.cpp:1891] Received subscription request for HTTP framework 'default'
I0107 03:34:33.133493 31117 master.cpp:1668] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0107 03:34:33.133785 31117 master.cpp:1982] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0107 03:34:33.134210 31121 hierarchical.cpp:260] Added framework 7121910a-a4b4-41e2-a6be-eee549ccd203-0000
I0107 03:34:33.134238 31124 master.hpp:1662] Sending heartbeat to 7121910a-a4b4-41e2-a6be-eee549ccd203-0000
I0107 03:34:33.134277 31121 hierarchical.cpp:1329] No resources available to allocate!
I0107 03:34:33.134311 31121 hierarchical.cpp:1423] No inverse offers to send out!
I0107 03:34:33.134337 31121 hierarchical.cpp:1079] Performed allocation for 0 slaves in 99856ns
I0107 03:34:33.134955 31121 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.5:44990
I0107 03:34:33.135385 31121 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.5:44990
I0107 03:34:33.135967 31118 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0107 03:34:33.136052 31130 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.5:44990
I0107 03:34:33.137452 31119 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0107 03:34:33.137866 31119 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.5:45655
I0107 03:34:33.137984 31119 master.cpp:2636] Processing REQUEST call for framework 7121910a-a4b4-41e2-a6be-eee549ccd203-0000 (default)
I0107 03:34:33.138177 31121 hierarchical.cpp:579] Received resource request from framework 7121910a-a4b4-41e2-a6be-eee549ccd203-0000
I0107 03:34:33.138537 31096 master.cpp:930] Master terminating
I0107 03:34:33.138769 31124 hierarchical.cpp:321] Removed framework 7121910a-a4b4-41e2-a6be-eee549ccd203-0000
E0107 03:34:33.139780 31125 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (556 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I0107 03:34:33.252970 31096 leveldb.cpp:174] Opened db in 107.614389ms
I0107 03:34:33.292935 31096 leveldb.cpp:181] Compacted db in 39.888534ms
I0107 03:34:33.292996 31096 leveldb.cpp:196] Created db iterator in 19754ns
I0107 03:34:33.293010 31096 leveldb.cpp:202] Seeked to beginning of db in 1536ns
I0107 03:34:33.293020 31096 leveldb.cpp:271] Iterated through 0 keys in the db in 298ns
I0107 03:34:33.293062 31096 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0107 03:34:33.293830 31128 recover.cpp:447] Starting replica recovery
I0107 03:34:33.294358 31128 recover.cpp:473] Replica is in EMPTY status
I0107 03:34:33.295356 31117 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (12980)@172.17.0.5:44990
I0107 03:34:33.295896 31124 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0107 03:34:33.296394 31118 recover.cpp:564] Updating replica status to STARTING
I0107 03:34:33.296762 31120 master.cpp:365] Master 6dd7c428-c54c-43d8-ac66-8dda5c551cef (d5186ce46002) started on 172.17.0.5:44990
I0107 03:34:33.296788 31120 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/mprxsM/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/mprxsM/master" --zk_session_timeout="10secs"
I0107 03:34:33.297045 31120 master.cpp:414] Master allowing unauthenticated frameworks to register
I0107 03:34:33.297055 31120 master.cpp:417] Master only allowing authenticated slaves to register
I0107 03:34:33.297061 31120 credentials.hpp:35] Loading credentials for authentication from '/tmp/mprxsM/credentials'
I0107 03:34:33.297338 31120 master.cpp:456] Using default 'crammd5' authenticator
I0107 03:34:33.297451 31120 master.cpp:493] Authorization enabled
I0107 03:34:33.297600 31117 hierarchical.cpp:147] Initialized hierarchical allocator process
I0107 03:34:33.297616 31115 whitelist_watcher.cpp:77] No whitelist given
I0107 03:34:33.299159 31129 master.cpp:1629] The newly elected leader is master@172.17.0.5:44990 with id 6dd7c428-c54c-43d8-ac66-8dda5c551cef
I0107 03:34:33.299275 31129 master.cpp:1642] Elected as the leading master!
I0107 03:34:33.299300 31129 master.cpp:1387] Recovering from registrar
I0107 03:34:33.299453 31121 registrar.cpp:307] Recovering registrar
I0107 03:34:33.318192 31116 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 21.675185ms
I0107 03:34:33.318254 31116 replica.cpp:320] Persisted replica status to STARTING
I0107 03:34:33.318511 31128 recover.cpp:473] Replica is in STARTING status
I0107 03:34:33.319538 31128 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (12981)@172.17.0.5:44990
I0107 03:34:33.319902 31116 recover.cpp:193] Received a recover response from a replica in STARTING status
I0107 03:34:33.320551 31130 recover.cpp:564] Updating replica status to VOTING
I0107 03:34:33.343682 31116 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.84763ms
I0107 03:34:33.343760 31116 replica.cpp:320] Persisted replica status to VOTING
I0107 03:34:33.343963 31125 recover.cpp:578] Successfully joined the Paxos group
I0107 03:34:33.344182 31125 recover.cpp:462] Recover process terminated
I0107 03:34:33.344689 31116 log.cpp:659] Attempting to start the writer
I0107 03:34:33.346174 31125 replica.cpp:493] Replica received implicit promise request from (12982)@172.17.0.5:44990 with proposal 1
I0107 03:34:33.368757 31125 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.508536ms
I0107 03:34:33.368859 31125 replica.cpp:342] Persisted promised to 1
I0107 03:34:33.369884 31125 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0107 03:34:33.371258 31119 replica.cpp:388] Replica received explicit promise request from (12983)@172.17.0.5:44990 for position 0 with proposal 2
I0107 03:34:33.393900 31119 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 22.575679ms
I0107 03:34:33.393987 31119 replica.cpp:712] Persisted action at 0
I0107 03:34:33.395462 31125 replica.cpp:537] Replica received write request for position 0 from (12984)@172.17.0.5:44990
I0107 03:34:33.395544 31125 leveldb.cpp:436] Reading position from leveldb took 39347ns
I0107 03:34:33.425209 31125 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 29.601471ms
I0107 03:34:33.425299 31125 replica.cpp:712] Persisted action at 0
I0107 03:34:33.426533 31129 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0107 03:34:33.470257 31129 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 43.671921ms
I0107 03:34:33.470341 31129 replica.cpp:712] Persisted action at 0
I0107 03:34:33.470372 31129 replica.cpp:697] Replica learned NOP action at position 0
I0107 03:34:33.471179 31118 log.cpp:675] Writer started with ending position 0
I0107 03:34:33.472347 31128 leveldb.cpp:436] Reading position from leveldb took 50595ns
I0107 03:34:33.473523 31116 registrar.cpp:340] Successfully fetched the registry (0B) in 174.03008ms
I0107 03:34:33.473690 31116 registrar.cpp:439] Applied 1 operations in 55175ns; attempting to update the 'registry'
I0107 03:34:33.474519 31126 log.cpp:683] Attempting to append 170 bytes to the log
I0107 03:34:33.474659 31129 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0107 03:34:33.475476 31127 replica.cpp:537] Replica received write request for position 1 from (12985)@172.17.0.5:44990
I0107 03:34:33.503849 31127 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 28.313627ms
I0107 03:34:33.503921 31127 replica.cpp:712] Persisted action at 1
I0107 03:34:33.504916 31126 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0107 03:34:33.535702 31126 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 30.746859ms
I0107 03:34:33.535739 31126 replica.cpp:712] Persisted action at 1
I0107 03:34:33.535759 31126 replica.cpp:697] Replica learned APPEND action at position 1
I0107 03:34:33.536845 31128 registrar.cpp:484] Successfully updated the 'registry' in 63.009792ms
I0107 03:34:33.536973 31128 registrar.cpp:370] Successfully recovered registrar
I0107 03:34:33.537114 31117 log.cpp:702] Attempting to truncate the log to 1
I0107 03:34:33.537361 31118 master.cpp:1439] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0107 03:34:33.537434 31117 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0107 03:34:33.537469 31118 hierarchical.cpp:165] Skipping recovery of hierarchical allocator: nothing to recover
I0107 03:34:33.538327 31130 replica.cpp:537] Replica received write request for position 2 from (12986)@172.17.0.5:44990
I0107 03:34:33.569425 31130 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 31.042949ms
I0107 03:34:33.569512 31130 replica.cpp:712] Persisted action at 2
I0107 03:34:33.570719 31124 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0107 03:34:33.595043 31124 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 24.124174ms
I0107 03:34:33.595224 31124 leveldb.cpp:399] Deleting ~1 keys from leveldb took 86455ns
I0107 03:34:33.595257 31124 replica.cpp:712] Persisted action at 2
I0107 03:34:33.595296 31124 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0107 03:34:33.599004 31096 scheduler.cpp:154] Version: 0.27.0
I0107 03:34:33.599820 31127 scheduler.cpp:236] New master detected at master@172.17.0.5:44990
I0107 03:34:33.601234 31118 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.5:44990
I0107 03:34:33.603260 31126 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0107 03:34:33.603740 31128 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.5:45661
I0107 03:34:33.604070 31128 master.cpp:1891] Received subscription request for HTTP framework 'default'
I0107 03:34:33.604130 31128 master.cpp:1668] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0107 03:34:33.604423 31128 master.cpp:1982] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0107 03:34:33.604853 31121 hierarchical.cpp:260] Added framework 6dd7c428-c54c-43d8-ac66-8dda5c551cef-0000
I0107 03:34:33.604923 31121 hierarchical.cpp:1329] No resources available to allocate!
I0107 03:34:33.604943 31128 master.hpp:1662] Sending heartbeat to 6dd7c428-c54c-43d8-ac66-8dda5c551cef-0000
I0107 03:34:33.604959 31121 hierarchical.cpp:1423] No inverse offers to send out!
I0107 03:34:33.604986 31121 hierarchical.cpp:1079] Performed allocation for 0 slaves in 105976ns
I0107 03:34:33.606186 31122 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.5:44990
I0107 03:34:33.606655 31122 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.5:44990
I0107 03:34:33.607200 31120 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0107 03:34:33.607481 31128 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.5:44990
I0107 03:34:33.608990 31123 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0107 03:34:33.609417 31121 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.5:45662
I0107 03:34:33.609704 31121 master.cpp:2636] Processing REQUEST call for framework 6dd7c428-c54c-43d8-ac66-8dda5c551cef-0000 (default)
I0107 03:34:33.609891 31119 hierarchical.cpp:579] Received resource request from framework 6dd7c428-c54c-43d8-ac66-8dda5c551cef-0000
I0107 03:34:33.610283 31115 master.cpp:930] Master terminating
I0107 03:34:33.610946 31124 hierarchical.cpp:321] Removed framework 6dd7c428-c54c-43d8-ac66-8dda5c551cef-0000
E0107 03:34:33.615695 31124 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (473 ms)
[----------] 22 tests from ContentType/SchedulerTest (18621 ms total)
[----------] Global test environment tear-down
2016-01-07 03:34:33,864:31096(0x2b5202774700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:36709] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
[==========] 923 tests from 120 test cases ran. (901250 ms total)
[ PASSED ] 922 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-1452135045-18967
Untagged: mesos-1452135045-18967:latest
Deleted: c3a1ba384c96138f56c772330ced238281d35c32bd692215c6ca14191c741b2d
Deleted: 67fe27601555a1d8c9eddb5c9a12b13e7a64b8796a37a9649a34eee148de6c43
Deleted: 33702f666d1e99152663209303f4ed45dd09c8c1c91cefc9b1550eb68e6a5114
Deleted: 9dc5a6260708eb152c96acdcd1e0001687d9c8f2665fcf272028d437e64a43ed
Deleted: 7c97a38cbb70ea7d4340d113c90ef84a280f95d9db4e1e0fd8b4c7b2223ea9cb
Deleted: a68283425dd902abbf43762f6e4b5904f4509306623bd90c53ecbf23581bb4e3
Deleted: fd0bac1355bd9a27814170b436ac6aefe75aacaac6c362d4a3c30abfff2bdcf3
Deleted: 63911d2286749c00344e58ed4708245a2cfbea1ef6f299cd0168bc8e145c3e82
Deleted: e92900f30f3061a81925cb552e4afe860160f734f5911c054a4c21053fe22c1d
Deleted: 42e6829fd0c10183ccf11364551cd93a672a7edd0fc11162e52e27484f5fb271
Deleted: ec58f47e06076acc7246178850f3c85df4120a871e974d0c2020684c3347b056
Deleted: 765e7764e40dc3ce6022c37c130afb40b9f70608f31eda08d46a51f648646e45
Deleted: 69ea42bff1bba3bdbea64c79b30e962213b79cc77d7e66374c58f2f19eaf2584
Error handling URL https://reviews.apache.org/api/review-requests/41963/reviews/: INTERNAL SERVER ERROR
git clean -fd
git reset --hard c258d8af79e20053e99aaf85491412f8ad58ab2e
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : mesos-reviewbot #10631
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/mesos-reviewbot/10631/>
Build failed in Jenkins: mesos-reviewbot #10630
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/mesos-reviewbot/10630/changes>
Changes:
[tnachen] Fixed posix filesystem isolator to not allow executors with image.
------------------------------------------
[...truncated 166167 lines...]
I0107 04:29:58.443732 31132 gc.cpp:54] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_mLlGPB/slaves/54e96a60-892a-43cc-9c7b-30b31add180d-S0/frameworks/54e96a60-892a-43cc-9c7b-30b31add180d-0000' for gc 6.99999486522074days in the future
[ OK ] ContentType/SchedulerTest.Message/1 (603 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I0107 04:29:58.535977 31098 leveldb.cpp:174] Opened db in 87.117085ms
I0107 04:29:58.583971 31098 leveldb.cpp:181] Compacted db in 47.951683ms
I0107 04:29:58.584036 31098 leveldb.cpp:196] Created db iterator in 20157ns
I0107 04:29:58.584058 31098 leveldb.cpp:202] Seeked to beginning of db in 2137ns
I0107 04:29:58.584069 31098 leveldb.cpp:271] Iterated through 0 keys in the db in 447ns
I0107 04:29:58.584106 31098 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0107 04:29:58.584619 31119 recover.cpp:447] Starting replica recovery
I0107 04:29:58.584908 31119 recover.cpp:473] Replica is in EMPTY status
I0107 04:29:58.586004 31132 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (12988)@172.17.0.2:48027
I0107 04:29:58.586602 31122 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0107 04:29:58.587213 31124 recover.cpp:564] Updating replica status to STARTING
I0107 04:29:58.588171 31120 master.cpp:365] Master 5b7ce243-8754-4c6c-b450-c94b95fbb8d2 (a41b009d642a) started on 172.17.0.2:48027
I0107 04:29:58.588310 31120 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/mDg5Dr/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/mDg5Dr/master" --zk_session_timeout="10secs"
I0107 04:29:58.588616 31120 master.cpp:414] Master allowing unauthenticated frameworks to register
I0107 04:29:58.588635 31120 master.cpp:417] Master only allowing authenticated slaves to register
I0107 04:29:58.588650 31120 credentials.hpp:35] Loading credentials for authentication from '/tmp/mDg5Dr/credentials'
I0107 04:29:58.588995 31120 master.cpp:456] Using default 'crammd5' authenticator
I0107 04:29:58.589190 31120 master.cpp:493] Authorization enabled
I0107 04:29:58.589366 31122 hierarchical.cpp:147] Initialized hierarchical allocator process
I0107 04:29:58.589372 31129 whitelist_watcher.cpp:77] No whitelist given
I0107 04:29:58.591145 31123 master.cpp:1629] The newly elected leader is master@172.17.0.2:48027 with id 5b7ce243-8754-4c6c-b450-c94b95fbb8d2
I0107 04:29:58.591187 31123 master.cpp:1642] Elected as the leading master!
I0107 04:29:58.591204 31123 master.cpp:1387] Recovering from registrar
I0107 04:29:58.591367 31124 registrar.cpp:307] Recovering registrar
I0107 04:29:58.621279 31125 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 33.849038ms
I0107 04:29:58.621323 31125 replica.cpp:320] Persisted replica status to STARTING
I0107 04:29:58.621609 31131 recover.cpp:473] Replica is in STARTING status
I0107 04:29:58.622552 31130 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (12989)@172.17.0.2:48027
I0107 04:29:58.622895 31131 recover.cpp:193] Received a recover response from a replica in STARTING status
I0107 04:29:58.623327 31130 recover.cpp:564] Updating replica status to VOTING
I0107 04:29:58.646291 31119 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.783827ms
I0107 04:29:58.646329 31119 replica.cpp:320] Persisted replica status to VOTING
I0107 04:29:58.646453 31119 recover.cpp:578] Successfully joined the Paxos group
I0107 04:29:58.646705 31119 recover.cpp:462] Recover process terminated
I0107 04:29:58.647249 31124 log.cpp:659] Attempting to start the writer
I0107 04:29:58.648628 31128 replica.cpp:493] Replica received implicit promise request from (12990)@172.17.0.2:48027 with proposal 1
I0107 04:29:58.671408 31128 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.735069ms
I0107 04:29:58.671447 31128 replica.cpp:342] Persisted promised to 1
I0107 04:29:58.672099 31130 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0107 04:29:58.673764 31125 replica.cpp:388] Replica received explicit promise request from (12991)@172.17.0.2:48027 for position 0 with proposal 2
I0107 04:29:58.696521 31125 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 22.699545ms
I0107 04:29:58.696558 31125 replica.cpp:712] Persisted action at 0
I0107 04:29:58.697688 31120 replica.cpp:537] Replica received write request for position 0 from (12992)@172.17.0.2:48027
I0107 04:29:58.697741 31120 leveldb.cpp:436] Reading position from leveldb took 25250ns
I0107 04:29:58.721742 31120 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 23.964999ms
I0107 04:29:58.721788 31120 replica.cpp:712] Persisted action at 0
I0107 04:29:58.722720 31121 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0107 04:29:58.746738 31121 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 23.980943ms
I0107 04:29:58.746778 31121 replica.cpp:712] Persisted action at 0
I0107 04:29:58.746800 31121 replica.cpp:697] Replica learned NOP action at position 0
I0107 04:29:58.747542 31128 log.cpp:675] Writer started with ending position 0
I0107 04:29:58.748806 31132 leveldb.cpp:436] Reading position from leveldb took 32487ns
I0107 04:29:58.749807 31117 registrar.cpp:340] Successfully fetched the registry (0B) in 158.379776ms
I0107 04:29:58.749946 31117 registrar.cpp:439] Applied 1 operations in 28814ns; attempting to update the 'registry'
I0107 04:29:58.750807 31118 log.cpp:683] Attempting to append 170 bytes to the log
I0107 04:29:58.750960 31119 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0107 04:29:58.751857 31130 replica.cpp:537] Replica received write request for position 1 from (12993)@172.17.0.2:48027
I0107 04:29:58.776720 31130 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 24.810479ms
I0107 04:29:58.776759 31130 replica.cpp:712] Persisted action at 1
I0107 04:29:58.777593 31128 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0107 04:29:58.801962 31128 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 24.330548ms
I0107 04:29:58.802001 31128 replica.cpp:712] Persisted action at 1
I0107 04:29:58.802023 31128 replica.cpp:697] Replica learned APPEND action at position 1
I0107 04:29:58.803158 31121 registrar.cpp:484] Successfully updated the 'registry' in 53.11488ms
I0107 04:29:58.803344 31121 registrar.cpp:370] Successfully recovered registrar
I0107 04:29:58.803427 31128 log.cpp:702] Attempting to truncate the log to 1
I0107 04:29:58.803587 31123 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0107 04:29:58.803922 31131 master.cpp:1439] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0107 04:29:58.803959 31121 hierarchical.cpp:165] Skipping recovery of hierarchical allocator: nothing to recover
I0107 04:29:58.804749 31122 replica.cpp:537] Replica received write request for position 2 from (12994)@172.17.0.2:48027
I0107 04:29:58.827008 31122 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 22.217275ms
I0107 04:29:58.827047 31122 replica.cpp:712] Persisted action at 2
I0107 04:29:58.828227 31127 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0107 04:29:58.852103 31127 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 23.835765ms
I0107 04:29:58.852203 31127 leveldb.cpp:399] Deleting ~1 keys from leveldb took 42784ns
I0107 04:29:58.852231 31127 replica.cpp:712] Persisted action at 2
I0107 04:29:58.852253 31127 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0107 04:29:58.854861 31098 scheduler.cpp:154] Version: 0.27.0
I0107 04:29:58.855496 31131 scheduler.cpp:236] New master detected at master@172.17.0.2:48027
I0107 04:29:58.856655 31128 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.2:48027
I0107 04:29:58.858613 31122 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0107 04:29:58.859088 31127 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:57391
I0107 04:29:58.859328 31127 master.cpp:1891] Received subscription request for HTTP framework 'default'
I0107 04:29:58.859391 31127 master.cpp:1668] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0107 04:29:58.859690 31127 master.cpp:1982] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0107 04:29:58.860164 31128 hierarchical.cpp:260] Added framework 5b7ce243-8754-4c6c-b450-c94b95fbb8d2-0000
I0107 04:29:58.860265 31127 master.hpp:1662] Sending heartbeat to 5b7ce243-8754-4c6c-b450-c94b95fbb8d2-0000
I0107 04:29:58.860282 31128 hierarchical.cpp:1329] No resources available to allocate!
I0107 04:29:58.860327 31128 hierarchical.cpp:1423] No inverse offers to send out!
I0107 04:29:58.860362 31128 hierarchical.cpp:1079] Performed allocation for 0 slaves in 122270ns
I0107 04:29:58.861057 31118 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.2:48027
I0107 04:29:58.861603 31118 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.2:48027
I0107 04:29:58.862491 31124 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0107 04:29:58.862565 31118 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.2:48027
I0107 04:29:58.864397 31129 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0107 04:29:58.864802 31132 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:57392
I0107 04:29:58.864922 31132 master.cpp:2636] Processing REQUEST call for framework 5b7ce243-8754-4c6c-b450-c94b95fbb8d2-0000 (default)
I0107 04:29:58.865092 31120 hierarchical.cpp:579] Received resource request from framework 5b7ce243-8754-4c6c-b450-c94b95fbb8d2-0000
I0107 04:29:58.865465 31126 master.cpp:930] Master terminating
I0107 04:29:58.865669 31127 hierarchical.cpp:321] Removed framework 5b7ce243-8754-4c6c-b450-c94b95fbb8d2-0000
E0107 04:29:58.866770 31126 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (424 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I0107 04:29:58.978643 31098 leveldb.cpp:174] Opened db in 105.704324ms
I0107 04:29:59.029067 31098 leveldb.cpp:181] Compacted db in 50.382382ms
I0107 04:29:59.029157 31098 leveldb.cpp:196] Created db iterator in 44306ns
I0107 04:29:59.029181 31098 leveldb.cpp:202] Seeked to beginning of db in 2077ns
I0107 04:29:59.029191 31098 leveldb.cpp:271] Iterated through 0 keys in the db in 330ns
I0107 04:29:59.029228 31098 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0107 04:29:59.029598 31129 recover.cpp:447] Starting replica recovery
I0107 04:29:59.029856 31128 recover.cpp:473] Replica is in EMPTY status
I0107 04:29:59.030674 31129 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (13002)@172.17.0.2:48027
I0107 04:29:59.031010 31124 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0107 04:29:59.031795 31126 master.cpp:365] Master 65ba95ba-f17c-49b6-a21e-40c532d29cc8 (a41b009d642a) started on 172.17.0.2:48027
I0107 04:29:59.031813 31126 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/C0lDdK/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/C0lDdK/master" --zk_session_timeout="10secs"
I0107 04:29:59.032078 31126 master.cpp:414] Master allowing unauthenticated frameworks to register
I0107 04:29:59.032094 31126 master.cpp:417] Master only allowing authenticated slaves to register
I0107 04:29:59.032105 31126 credentials.hpp:35] Loading credentials for authentication from '/tmp/C0lDdK/credentials'
I0107 04:29:59.032073 31127 recover.cpp:564] Updating replica status to STARTING
I0107 04:29:59.032423 31126 master.cpp:456] Using default 'crammd5' authenticator
I0107 04:29:59.032526 31126 master.cpp:493] Authorization enabled
I0107 04:29:59.032662 31123 hierarchical.cpp:147] Initialized hierarchical allocator process
I0107 04:29:59.032670 31122 whitelist_watcher.cpp:77] No whitelist given
I0107 04:29:59.034036 31128 master.cpp:1629] The newly elected leader is master@172.17.0.2:48027 with id 65ba95ba-f17c-49b6-a21e-40c532d29cc8
I0107 04:29:59.034062 31128 master.cpp:1642] Elected as the leading master!
I0107 04:29:59.034075 31128 master.cpp:1387] Recovering from registrar
I0107 04:29:59.034198 31122 registrar.cpp:307] Recovering registrar
I0107 04:29:59.059773 31117 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 27.454023ms
I0107 04:29:59.059801 31117 replica.cpp:320] Persisted replica status to STARTING
I0107 04:29:59.060003 31132 recover.cpp:473] Replica is in STARTING status
I0107 04:29:59.061422 31125 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (13003)@172.17.0.2:48027
I0107 04:29:59.061703 31120 recover.cpp:193] Received a recover response from a replica in STARTING status
I0107 04:29:59.062099 31121 recover.cpp:564] Updating replica status to VOTING
I0107 04:29:59.084894 31131 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.639225ms
I0107 04:29:59.084933 31131 replica.cpp:320] Persisted replica status to VOTING
I0107 04:29:59.085067 31128 recover.cpp:578] Successfully joined the Paxos group
I0107 04:29:59.085340 31128 recover.cpp:462] Recover process terminated
I0107 04:29:59.085819 31132 log.cpp:659] Attempting to start the writer
I0107 04:29:59.087194 31130 replica.cpp:493] Replica received implicit promise request from (13004)@172.17.0.2:48027 with proposal 1
I0107 04:29:59.110074 31130 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.832589ms
I0107 04:29:59.110118 31130 replica.cpp:342] Persisted promised to 1
I0107 04:29:59.110780 31128 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0107 04:29:59.112239 31129 replica.cpp:388] Replica received explicit promise request from (13005)@172.17.0.2:48027 for position 0 with proposal 2
I0107 04:29:59.135120 31129 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 22.827822ms
I0107 04:29:59.135177 31129 replica.cpp:712] Persisted action at 0
I0107 04:29:59.136430 31127 replica.cpp:537] Replica received write request for position 0 from (13006)@172.17.0.2:48027
I0107 04:29:59.136500 31127 leveldb.cpp:436] Reading position from leveldb took 30374ns
I0107 04:29:59.160296 31127 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 23.748144ms
I0107 04:29:59.160334 31127 replica.cpp:712] Persisted action at 0
I0107 04:29:59.161036 31124 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0107 04:29:59.189476 31124 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 28.396448ms
I0107 04:29:59.189515 31124 replica.cpp:712] Persisted action at 0
I0107 04:29:59.189538 31124 replica.cpp:697] Replica learned NOP action at position 0
I0107 04:29:59.190294 31117 log.cpp:675] Writer started with ending position 0
I0107 04:29:59.191445 31131 leveldb.cpp:436] Reading position from leveldb took 32054ns
I0107 04:29:59.192335 31121 registrar.cpp:340] Successfully fetched the registry (0B) in 158.091008ms
I0107 04:29:59.192464 31121 registrar.cpp:439] Applied 1 operations in 31597ns; attempting to update the 'registry'
I0107 04:29:59.193284 31126 log.cpp:683] Attempting to append 170 bytes to the log
I0107 04:29:59.193409 31129 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0107 04:29:59.194196 31128 replica.cpp:537] Replica received write request for position 1 from (13007)@172.17.0.2:48027
I0107 04:29:59.222856 31128 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 28.608916ms
I0107 04:29:59.222892 31128 replica.cpp:712] Persisted action at 1
I0107 04:29:59.223728 31132 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0107 04:29:59.256309 31132 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 32.543403ms
I0107 04:29:59.256350 31132 replica.cpp:712] Persisted action at 1
I0107 04:29:59.256372 31132 replica.cpp:697] Replica learned APPEND action at position 1
I0107 04:29:59.257474 31131 registrar.cpp:484] Successfully updated the 'registry' in 64.93824ms
I0107 04:29:59.257661 31131 registrar.cpp:370] Successfully recovered registrar
I0107 04:29:59.257819 31129 log.cpp:702] Attempting to truncate the log to 1
I0107 04:29:59.257977 31127 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0107 04:29:59.258313 31122 master.cpp:1439] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0107 04:29:59.258396 31120 hierarchical.cpp:165] Skipping recovery of hierarchical allocator: nothing to recover
I0107 04:29:59.259207 31124 replica.cpp:537] Replica received write request for position 2 from (13008)@172.17.0.2:48027
I0107 04:29:59.289814 31124 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 30.563366ms
I0107 04:29:59.289854 31124 replica.cpp:712] Persisted action at 2
I0107 04:29:59.290575 31127 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0107 04:29:59.323197 31127 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 32.583587ms
I0107 04:29:59.323274 31127 leveldb.cpp:399] Deleting ~1 keys from leveldb took 39586ns
I0107 04:29:59.323302 31127 replica.cpp:712] Persisted action at 2
I0107 04:29:59.323324 31127 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0107 04:29:59.329491 31098 scheduler.cpp:154] Version: 0.27.0
I0107 04:29:59.330242 31120 scheduler.cpp:236] New master detected at master@172.17.0.2:48027
I0107 04:29:59.331492 31118 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.2:48027
I0107 04:29:59.333608 31128 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0107 04:29:59.334192 31127 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:57393
I0107 04:29:59.334560 31127 master.cpp:1891] Received subscription request for HTTP framework 'default'
I0107 04:29:59.334625 31127 master.cpp:1668] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0107 04:29:59.334930 31127 master.cpp:1982] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0107 04:29:59.335330 31129 hierarchical.cpp:260] Added framework 65ba95ba-f17c-49b6-a21e-40c532d29cc8-0000
I0107 04:29:59.335402 31129 hierarchical.cpp:1329] No resources available to allocate!
I0107 04:29:59.335464 31128 master.hpp:1662] Sending heartbeat to 65ba95ba-f17c-49b6-a21e-40c532d29cc8-0000
I0107 04:29:59.335599 31129 hierarchical.cpp:1423] No inverse offers to send out!
I0107 04:29:59.335705 31129 hierarchical.cpp:1079] Performed allocation for 0 slaves in 341248ns
I0107 04:29:59.336469 31132 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.2:48027
I0107 04:29:59.337051 31132 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.2:48027
I0107 04:29:59.337710 31118 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0107 04:29:59.337709 31132 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.2:48027
I0107 04:29:59.339460 31119 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0107 04:29:59.339967 31120 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:57394
I0107 04:29:59.340237 31120 master.cpp:2636] Processing REQUEST call for framework 65ba95ba-f17c-49b6-a21e-40c532d29cc8-0000 (default)
I0107 04:29:59.340410 31130 hierarchical.cpp:579] Received resource request from framework 65ba95ba-f17c-49b6-a21e-40c532d29cc8-0000
I0107 04:29:59.340788 31132 master.cpp:930] Master terminating
I0107 04:29:59.340986 31119 hierarchical.cpp:321] Removed framework 65ba95ba-f17c-49b6-a21e-40c532d29cc8-0000
E0107 04:29:59.342111 31122 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (475 ms)
[----------] 22 tests from ContentType/SchedulerTest (13955 ms total)
[----------] Global test environment tear-down
[==========] 923 tests from 120 test cases ran. (674003 ms total)
[ PASSED ] 922 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-1452138811-29974
Untagged: mesos-1452138811-29974:latest
Deleted: 825d0b34a4690972e49ef80faedb49de250499ada4a86ed41fb52c4bbac17f4f
Deleted: 4c78661d2f87dee78f947eca5935ea42f56213ee0f9f43dc6ddc84b2d3067d77
Deleted: d54e56816e47584b8faf65fd9c7b548c6259a419302d9ad48e41b8101392a028
Deleted: b4b8ad1dc7a8706be381c298ce7341123c515667047b0c169a0099c149237e87
Deleted: 791e82fdec121a13b193251fccc238e5ab49d7652d0d2d8d03de91aced5e86d4
Deleted: da37d7ed3b9a87a7e9005b31f65e140ba06e8de733b2b3449d5fe2ea9bd7e68e
Deleted: 24c56b3a05e479a477b2a337ebaf322d6b93d2c9f819911517e89331bc8c9546
Deleted: 2e09ea81410d5764da87353979f53a92b6a5f6f47e01697b1e05a3a801242e4f
Deleted: 8ae28a00f8ce9c7ba604a29f9729e418d64909ceb9ca3abd824e858596ad12b3
Deleted: af3ec58e3b0d16568957a705cfde0dbad0ff58c7e7401f13ae9f453da3096986
Deleted: 96a6982787904e13df19509171df7e38dd1892794d8943079bcc76a9b0463692
Deleted: d853583bead3253a6af8a2c56f020f6d2f376826b0e6458817ecb44ef702f31d
Deleted: 28a62600a4116118b439a8c9d84af970da3ba827cc8bd1290f1d3977f5267935
Error handling URL https://reviews.apache.org/api/review-requests/41963/reviews/: INTERNAL SERVER ERROR
git clean -fd
git reset --hard 52abf8de380cf7a3c3d8a2e5616b3d34d7b6b277
Build step 'Execute shell' marked build as failure