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/09/21 06:46:24 UTC
Build failed in Jenkins: Mesos » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6) #2658
See <https://builds.apache.org/job/Mesos/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)/2658/changes>
Changes:
[vinodkone] Missing `endl` in `cout` for default executor.
------------------------------------------
[...truncated 71905 lines...]
I0921 06:45:44.577169 31170 slave.cpp:1138] Checkpointing SlaveInfo to '/tmp/MasterMaintenanceTest_InverseOffersFilters_YUgj1b/meta/slaves/c77b2c37-1de7-4556-b6a3-616c186154b6-S0/slave.info'
I0921 06:45:44.577531 31170 slave.cpp:1175] Forwarding total oversubscribed resources
I0921 06:45:44.577697 31170 master.cpp:5508] Received update of agent c77b2c37-1de7-4556-b6a3-616c186154b6-S0 at slave(138)@172.17.0.2:46479 (maintenance-host) with total oversubscribed resources
I0921 06:45:44.577782 31160 replica.cpp:691] Replica received learned notice for position 4 from @0.0.0.0:0
I0921 06:45:44.577929 31160 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 116492ns
I0921 06:45:44.577992 31160 leveldb.cpp:399] Deleting ~2 keys from leveldb took 38528ns
I0921 06:45:44.577983 31170 hierarchical.cpp:546] Agent c77b2c37-1de7-4556-b6a3-616c186154b6-S0 (maintenance-host) updated with oversubscribed resources (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: )
I0921 06:45:44.578019 31160 replica.cpp:708] Persisted action TRUNCATE at position 4
I0921 06:45:44.578145 31170 hierarchical.cpp:1685] No allocations performed
I0921 06:45:44.578208 31170 hierarchical.cpp:1300] Performed allocation for agent c77b2c37-1de7-4556-b6a3-616c186154b6-S0 in 180612ns
I0921 06:45:44.578326 31162 status_update_manager.cpp:184] Resuming sending status updates
I0921 06:45:44.581013 31138 cluster.cpp:435] Creating default 'local' authorizer
I0921 06:45:44.582651 31168 slave.cpp:208] Mesos agent started on (139)@172.17.0.2:46479
I0921 06:45:44.582672 31168 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/MasterMaintenanceTest_InverseOffersFilters_Adbvkm/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterMaintenanceTest_InverseOffersFilters_Adbvkm/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname="maintenance-host-2" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/MasterMaintenanceTest_InverseOffersFilters_Adbvkm/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.1.0/_build/src" --logbufsecs="0" --logging_level="INFO" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/MasterMaintenanceTest_InverseOffersFilters_Adbvkm" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/MasterMaintenanceTest_InverseOffersFilters_ICIYrV"
I0921 06:45:44.583254 31168 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterMaintenanceTest_InverseOffersFilters_Adbvkm/credential'
I0921 06:45:44.583430 31168 slave.cpp:346] Agent using credential for: test-principal
I0921 06:45:44.583449 31168 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterMaintenanceTest_InverseOffersFilters_Adbvkm/http_credentials'
I0921 06:45:44.583679 31168 http.cpp:883] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0921 06:45:44.583851 31168 http.cpp:883] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0921 06:45:44.585062 31168 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0921 06:45:44.585166 31168 slave.cpp:541] Agent attributes: [ ]
I0921 06:45:44.585183 31168 slave.cpp:546] Agent hostname: maintenance-host-2
I0921 06:45:44.586913 31158 state.cpp:57] Recovering state from '/tmp/MasterMaintenanceTest_InverseOffersFilters_ICIYrV/meta'
I0921 06:45:44.587239 31167 status_update_manager.cpp:203] Recovering status update manager
I0921 06:45:44.587590 31166 slave.cpp:5227] Finished recovery
I0921 06:45:44.588088 31166 slave.cpp:5399] Querying resource estimator for oversubscribable resources
I0921 06:45:44.588416 31171 status_update_manager.cpp:177] Pausing sending status updates
I0921 06:45:44.588418 31157 slave.cpp:915] New master detected at master@172.17.0.2:46479
I0921 06:45:44.588462 31157 slave.cpp:974] Authenticating with master master@172.17.0.2:46479
I0921 06:45:44.588534 31157 slave.cpp:985] Using default CRAM-MD5 authenticatee
I0921 06:45:44.588685 31157 slave.cpp:947] Detecting new master
I0921 06:45:44.588742 31163 authenticatee.cpp:121] Creating new client SASL connection
I0921 06:45:44.588865 31157 slave.cpp:5413] Received oversubscribable resources from the resource estimator
I0921 06:45:44.589001 31159 master.cpp:6583] Authenticating slave(139)@172.17.0.2:46479
I0921 06:45:44.589109 31158 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(337)@172.17.0.2:46479
I0921 06:45:44.589340 31169 authenticator.cpp:98] Creating new server SASL connection
I0921 06:45:44.589545 31160 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0921 06:45:44.589576 31160 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0921 06:45:44.589684 31163 authenticator.cpp:204] Received SASL authentication start
I0921 06:45:44.589741 31163 authenticator.cpp:326] Authentication requires more steps
I0921 06:45:44.589872 31160 authenticatee.cpp:259] Received SASL authentication step
I0921 06:45:44.590025 31166 authenticator.cpp:232] Received SASL authentication step
I0921 06:45:44.590066 31166 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9b376cdf7066' server FQDN: '9b376cdf7066' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0921 06:45:44.590092 31166 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0921 06:45:44.590126 31166 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0921 06:45:44.590149 31166 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9b376cdf7066' server FQDN: '9b376cdf7066' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0921 06:45:44.590164 31166 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0921 06:45:44.590175 31166 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0921 06:45:44.590193 31166 authenticator.cpp:318] Authentication success
I0921 06:45:44.590294 31160 authenticatee.cpp:299] Authentication success
I0921 06:45:44.590343 31171 master.cpp:6613] Successfully authenticated principal 'test-principal' at slave(139)@172.17.0.2:46479
I0921 06:45:44.590405 31157 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(337)@172.17.0.2:46479
I0921 06:45:44.590720 31165 slave.cpp:1069] Successfully authenticated with master master@172.17.0.2:46479
I0921 06:45:44.590863 31165 slave.cpp:1475] Will retry registration in 12.326699ms if necessary
I0921 06:45:44.591045 31164 master.cpp:5040] Registering agent at slave(139)@172.17.0.2:46479 (maintenance-host-2) with id c77b2c37-1de7-4556-b6a3-616c186154b6-S1
I0921 06:45:44.591506 31159 registrar.cpp:461] Applied 1 operations in 67400ns; attempting to update the registry
I0921 06:45:44.592420 31161 log.cpp:577] Attempting to append 513 bytes to the log
I0921 06:45:44.592564 31171 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 5
I0921 06:45:44.593293 31157 replica.cpp:537] Replica received write request for position 5 from __req_res__(2377)@172.17.0.2:46479
I0921 06:45:44.593487 31157 leveldb.cpp:341] Persisting action (532 bytes) to leveldb took 153451ns
I0921 06:45:44.593514 31157 replica.cpp:708] Persisted action APPEND at position 5
I0921 06:45:44.594231 31169 replica.cpp:691] Replica received learned notice for position 5 from @0.0.0.0:0
I0921 06:45:44.594650 31169 leveldb.cpp:341] Persisting action (534 bytes) to leveldb took 380554ns
I0921 06:45:44.594677 31169 replica.cpp:708] Persisted action APPEND at position 5
I0921 06:45:44.596431 31172 registrar.cpp:506] Successfully updated the registry in 4.856064ms
I0921 06:45:44.596621 31164 log.cpp:596] Attempting to truncate the log to 5
I0921 06:45:44.596762 31163 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 6
I0921 06:45:44.597491 31164 slave.cpp:4089] Received ping from slave-observer(143)@172.17.0.2:46479
I0921 06:45:44.597436 31170 master.cpp:5111] Registered agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 at slave(139)@172.17.0.2:46479 (maintenance-host-2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0921 06:45:44.597842 31164 slave.cpp:1115] Registered with master master@172.17.0.2:46479; given agent ID c77b2c37-1de7-4556-b6a3-616c186154b6-S1
I0921 06:45:44.597834 31163 hierarchical.cpp:482] Added agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 (maintenance-host-2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: )
I0921 06:45:44.597867 31164 fetcher.cpp:86] Clearing fetcher cache
I0921 06:45:44.598022 31168 replica.cpp:537] Replica received write request for position 6 from __req_res__(2378)@172.17.0.2:46479
I0921 06:45:44.598031 31162 status_update_manager.cpp:184] Resuming sending status updates
I0921 06:45:44.598047 31163 hierarchical.cpp:1685] No allocations performed
I0921 06:45:44.598145 31163 hierarchical.cpp:1300] Performed allocation for agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 in 263331ns
I0921 06:45:44.598223 31164 slave.cpp:1138] Checkpointing SlaveInfo to '/tmp/MasterMaintenanceTest_InverseOffersFilters_ICIYrV/meta/slaves/c77b2c37-1de7-4556-b6a3-616c186154b6-S1/slave.info'
I0921 06:45:44.598517 31168 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 447921ns
I0921 06:45:44.598542 31168 replica.cpp:708] Persisted action TRUNCATE at position 6
I0921 06:45:44.598740 31164 slave.cpp:1175] Forwarding total oversubscribed resources
I0921 06:45:44.599000 31160 master.cpp:5508] Received update of agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 at slave(139)@172.17.0.2:46479 (maintenance-host-2) with total oversubscribed resources
I0921 06:45:44.599462 31164 replica.cpp:691] Replica received learned notice for position 6 from @0.0.0.0:0
I0921 06:45:44.599454 31159 hierarchical.cpp:546] Agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 (maintenance-host-2) updated with oversubscribed resources (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: )
I0921 06:45:44.599619 31159 hierarchical.cpp:1685] No allocations performed
I0921 06:45:44.599629 31164 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 130163ns
I0921 06:45:44.599684 31159 hierarchical.cpp:1300] Performed allocation for agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 in 175398ns
I0921 06:45:44.599709 31164 leveldb.cpp:399] Deleting ~2 keys from leveldb took 50691ns
I0921 06:45:44.599743 31164 replica.cpp:708] Persisted action TRUNCATE at position 6
I0921 06:45:44.600493 31157 process.cpp:3336] Handling HTTP event for process 'master' with path: '/master/maintenance/schedule'
I0921 06:45:44.601624 31164 http.cpp:382] HTTP POST for /master/maintenance/schedule from 172.17.0.2:32820
I0921 06:45:44.602550 31169 registrar.cpp:461] Applied 1 operations in 166026ns; attempting to update the registry
I0921 06:45:44.603627 31166 log.cpp:577] Attempting to append 732 bytes to the log
I0921 06:45:44.603909 31171 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 7
I0921 06:45:44.604677 31168 replica.cpp:537] Replica received write request for position 7 from __req_res__(2379)@172.17.0.2:46479
I0921 06:45:44.604905 31168 leveldb.cpp:341] Persisting action (751 bytes) to leveldb took 178366ns
I0921 06:45:44.604945 31168 replica.cpp:708] Persisted action APPEND at position 7
I0921 06:45:44.605667 31163 replica.cpp:691] Replica received learned notice for position 7 from @0.0.0.0:0
I0921 06:45:44.606122 31163 leveldb.cpp:341] Persisting action (753 bytes) to leveldb took 414315ns
I0921 06:45:44.606153 31163 replica.cpp:708] Persisted action APPEND at position 7
I0921 06:45:44.608824 31167 registrar.cpp:506] Successfully updated the registry in 6.203904ms
I0921 06:45:44.609055 31162 log.cpp:596] Attempting to truncate the log to 7
I0921 06:45:44.609230 31157 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 8
I0921 06:45:44.609475 31165 master.cpp:5564] Updating unavailability of agent c77b2c37-1de7-4556-b6a3-616c186154b6-S0 at slave(138)@172.17.0.2:46479 (maintenance-host), starting at 2442.89813590989weeks
I0921 06:45:44.609802 31165 master.cpp:5564] Updating unavailability of agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 at slave(139)@172.17.0.2:46479 (maintenance-host-2), starting at 2442.89813590989weeks
I0921 06:45:44.610002 31157 hierarchical.cpp:1685] No allocations performed
I0921 06:45:44.610070 31157 hierarchical.cpp:1300] Performed allocation for agent c77b2c37-1de7-4556-b6a3-616c186154b6-S0 in 232049ns
I0921 06:45:44.610290 31157 hierarchical.cpp:1685] No allocations performed
I0921 06:45:44.610352 31157 hierarchical.cpp:1300] Performed allocation for agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 in 173709ns
I0921 06:45:44.610682 31157 replica.cpp:537] Replica received write request for position 8 from __req_res__(2380)@172.17.0.2:46479
I0921 06:45:44.611059 31157 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 326846ns
I0921 06:45:44.611090 31157 replica.cpp:708] Persisted action TRUNCATE at position 8
I0921 06:45:44.611979 31158 replica.cpp:691] Replica received learned notice for position 8 from @0.0.0.0:0
I0921 06:45:44.612287 31138 scheduler.cpp:176] Version: 1.1.0
I0921 06:45:44.612345 31158 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 337868ns
I0921 06:45:44.612414 31158 leveldb.cpp:399] Deleting ~2 keys from leveldb took 45428ns
I0921 06:45:44.612432 31158 replica.cpp:708] Persisted action TRUNCATE at position 8
I0921 06:45:44.612915 31166 scheduler.cpp:465] New master detected at master@172.17.0.2:46479
I0921 06:45:44.612944 31166 scheduler.cpp:474] Waiting for 0ns before initiating a re-(connection) attempt with the master
I0921 06:45:44.614563 31172 scheduler.cpp:353] Connected with the master at http://172.17.0.2:46479/master/api/v1/scheduler
I0921 06:45:44.615628 31157 scheduler.cpp:235] Sending SUBSCRIBE call to http://172.17.0.2:46479/master/api/v1/scheduler
I0921 06:45:44.616323 31162 process.cpp:3336] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0921 06:45:44.617158 31158 http.cpp:382] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:32822
I0921 06:45:44.617336 31158 master.cpp:2309] Received subscription request for HTTP framework 'default'
I0921 06:45:44.617389 31158 master.cpp:2049] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0921 06:45:44.617753 31165 master.cpp:2407] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0921 06:45:44.618355 31161 hierarchical.cpp:272] Added framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
I0921 06:45:44.618464 31162 master.hpp:2162] Sending heartbeat to c77b2c37-1de7-4556-b6a3-616c186154b6-0000
I0921 06:45:44.619231 31167 scheduler.cpp:666] Enqueuing event SUBSCRIBED received from http://172.17.0.2:46479/master/api/v1/scheduler
I0921 06:45:44.619892 31167 scheduler.cpp:666] Enqueuing event HEARTBEAT received from http://172.17.0.2:46479/master/api/v1/scheduler
I0921 06:45:44.620586 31171 master_maintenance_tests.cpp:175] Ignoring HEARTBEAT event
I0921 06:45:44.620697 31161 hierarchical.cpp:1277] Performed allocation for 2 agents in 2.310674ms
I0921 06:45:44.621461 31172 master.cpp:6412] Sending 2 offers to framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 (default)
I0921 06:45:44.622498 31172 master.cpp:6502] Sending 2 inverse offers to framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 (default)
I0921 06:45:44.624071 31157 scheduler.cpp:666] Enqueuing event OFFERS received from http://172.17.0.2:46479/master/api/v1/scheduler
I0921 06:45:44.625038 31157 scheduler.cpp:666] Enqueuing event INVERSE_OFFERS received from http://172.17.0.2:46479/master/api/v1/scheduler
I0921 06:45:44.626667 31160 hierarchical.cpp:1685] No allocations performed
I0921 06:45:44.626986 31160 hierarchical.cpp:1277] Performed allocation for 2 agents in 449517ns
I0921 06:45:44.628167 31171 scheduler.cpp:235] Sending ACCEPT call to http://172.17.0.2:46479/master/api/v1/scheduler
I0921 06:45:44.628940 31171 scheduler.cpp:235] Sending ACCEPT call to http://172.17.0.2:46479/master/api/v1/scheduler
I0921 06:45:44.629108 31158 process.cpp:3336] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0921 06:45:44.629750 31171 process.cpp:3336] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0921 06:45:44.630563 31160 http.cpp:382] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:32821
I0921 06:45:44.631300 31160 master.cpp:3521] Processing ACCEPT call for offers: [ c77b2c37-1de7-4556-b6a3-616c186154b6-O0 ] on agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 at slave(139)@172.17.0.2:46479 (maintenance-host-2) for framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 (default)
I0921 06:45:44.631395 31160 master.cpp:3143] Authorizing framework principal 'test-principal' to launch task e133c1d9-394b-45b7-9a78-90a87b31d7ce
I0921 06:45:44.632381 31160 http.cpp:382] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:32821
I0921 06:45:44.633518 31160 master.cpp:3521] Processing ACCEPT call for offers: [ c77b2c37-1de7-4556-b6a3-616c186154b6-O1 ] on agent c77b2c37-1de7-4556-b6a3-616c186154b6-S0 at slave(138)@172.17.0.2:46479 (maintenance-host) for framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 (default)
I0921 06:45:44.633666 31160 master.cpp:3143] Authorizing framework principal 'test-principal' to launch task ff0ce124-f019-4c0c-90a5-a5ff04a7c2d8
W0921 06:45:44.636899 31160 validation.cpp:916] Executor 'executor-1' for task 'e133c1d9-394b-45b7-9a78-90a87b31d7ce' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0921 06:45:44.636947 31160 validation.cpp:928] Executor 'executor-1' for task 'e133c1d9-394b-45b7-9a78-90a87b31d7ce' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0921 06:45:44.637576 31160 master.cpp:8159] Adding task e133c1d9-394b-45b7-9a78-90a87b31d7ce with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 (maintenance-host-2)
I0921 06:45:44.637992 31160 master.cpp:4121] Launching task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 (default) with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 at slave(139)@172.17.0.2:46479 (maintenance-host-2)
I0921 06:45:44.638564 31171 slave.cpp:1539] Got assigned task 'e133c1d9-394b-45b7-9a78-90a87b31d7ce' for framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
I0921 06:45:44.639427 31171 slave.cpp:1696] Launching task 'e133c1d9-394b-45b7-9a78-90a87b31d7ce' for framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
W0921 06:45:44.639868 31160 validation.cpp:916] Executor 'executor-2' for task 'ff0ce124-f019-4c0c-90a5-a5ff04a7c2d8' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0921 06:45:44.639906 31160 validation.cpp:928] Executor 'executor-2' for task 'ff0ce124-f019-4c0c-90a5-a5ff04a7c2d8' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0921 06:45:44.640231 31171 paths.cpp:536] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_ICIYrV/slaves/c77b2c37-1de7-4556-b6a3-616c186154b6-S1/frameworks/c77b2c37-1de7-4556-b6a3-616c186154b6-0000/executors/executor-1/runs/11d8af52-90ce-45e8-80bb-6668bc654f18' to user 'mesos'
I0921 06:45:44.640296 31160 master.cpp:8159] Adding task ff0ce124-f019-4c0c-90a5-a5ff04a7c2d8 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent c77b2c37-1de7-4556-b6a3-616c186154b6-S0 (maintenance-host)
I0921 06:45:44.643380 31160 master.cpp:4121] Launching task ff0ce124-f019-4c0c-90a5-a5ff04a7c2d8 of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 (default) with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent c77b2c37-1de7-4556-b6a3-616c186154b6-S0 at slave(138)@172.17.0.2:46479 (maintenance-host)
I0921 06:45:44.644194 31162 slave.cpp:1539] Got assigned task 'ff0ce124-f019-4c0c-90a5-a5ff04a7c2d8' for framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
I0921 06:45:44.644804 31162 slave.cpp:1696] Launching task 'ff0ce124-f019-4c0c-90a5-a5ff04a7c2d8' for framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
I0921 06:45:44.645376 31162 paths.cpp:536] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_YUgj1b/slaves/c77b2c37-1de7-4556-b6a3-616c186154b6-S0/frameworks/c77b2c37-1de7-4556-b6a3-616c186154b6-0000/executors/executor-2/runs/4f76d764-b84b-4e4b-831c-ef85e72fac9c' to user 'mesos'
I0921 06:45:44.649134 31171 slave.cpp:6110] Launching executor 'executor-1' of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 with resources in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_ICIYrV/slaves/c77b2c37-1de7-4556-b6a3-616c186154b6-S1/frameworks/c77b2c37-1de7-4556-b6a3-616c186154b6-0000/executors/executor-1/runs/11d8af52-90ce-45e8-80bb-6668bc654f18'
I0921 06:45:44.651036 31162 slave.cpp:6110] Launching executor 'executor-2' of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 with resources in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_YUgj1b/slaves/c77b2c37-1de7-4556-b6a3-616c186154b6-S0/frameworks/c77b2c37-1de7-4556-b6a3-616c186154b6-0000/executors/executor-2/runs/4f76d764-b84b-4e4b-831c-ef85e72fac9c'
*** Aborted at 1474440344 (unix time) try "date -d @1474440344" if you are using GNU date ***
I0921 06:45:44.651903 31171 exec.cpp:162] Version: 1.1.0
I0921 06:45:44.652312 31157 exec.cpp:212] Executor started at: executor(60)@172.17.0.2:46479 with pid 31138
I0921 06:45:44.652678 31171 slave.cpp:1982] Queued task 'e133c1d9-394b-45b7-9a78-90a87b31d7ce' for executor 'executor-1' of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
I0921 06:45:44.652762 31171 slave.cpp:868] Successfully attached file '/tmp/MasterMaintenanceTest_InverseOffersFilters_ICIYrV/slaves/c77b2c37-1de7-4556-b6a3-616c186154b6-S1/frameworks/c77b2c37-1de7-4556-b6a3-616c186154b6-0000/executors/executor-1/runs/11d8af52-90ce-45e8-80bb-6668bc654f18'
I0921 06:45:44.652858 31171 slave.cpp:3203] Got registration for executor 'executor-1' of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 from executor(60)@172.17.0.2:46479
PC: @ 0x7f873a76e4ed __GI_getenv
I0921 06:45:44.653328 31164 exec.cpp:237] Executor registered on agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1
I0921 06:45:44.653384 31164 exec.cpp:249] Executor::registered took 30036ns
*** SIGSEGV (@0x20) received by PID 31138 (TID 0x7f8730711700) from PID 32; stack trace: ***
I0921 06:45:44.654062 31171 slave.cpp:2186] Sending queued task 'e133c1d9-394b-45b7-9a78-90a87b31d7ce' to executor 'executor-1' of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 at executor(60)@172.17.0.2:46479
I0921 06:45:44.654408 31159 exec.cpp:324] Executor asked to run task 'e133c1d9-394b-45b7-9a78-90a87b31d7ce'
@ 0x7f8728e3f2f5 (unknown)
I0921 06:45:44.654507 31159 exec.cpp:333] Executor::launchTask took 74492ns
I0921 06:45:44.654623 31159 exec.cpp:550] Executor sending status update TASK_RUNNING (UUID: 554a8f0b-3c25-4647-abba-b2fcade3e962) for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
I0921 06:45:44.654983 31160 slave.cpp:3606] Handling status update TASK_RUNNING (UUID: 554a8f0b-3c25-4647-abba-b2fcade3e962) for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 from executor(60)@172.17.0.2:46479
@ 0x7f8728e43ec1 (unknown)
I0921 06:45:44.655642 31159 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 554a8f0b-3c25-4647-abba-b2fcade3e962) for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
I0921 06:45:44.655690 31159 status_update_manager.cpp:500] Creating StatusUpdate stream for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
@ 0x7f8728e381b8 (unknown)
I0921 06:45:44.656296 31159 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 554a8f0b-3c25-4647-abba-b2fcade3e962) for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 to the agent
I0921 06:45:44.656617 31170 slave.cpp:4007] Forwarding the update TASK_RUNNING (UUID: 554a8f0b-3c25-4647-abba-b2fcade3e962) for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 to master@172.17.0.2:46479
I0921 06:45:44.656970 31170 slave.cpp:3901] Status update manager successfully handled status update TASK_RUNNING (UUID: 554a8f0b-3c25-4647-abba-b2fcade3e962) for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
I0921 06:45:44.657042 31170 slave.cpp:3917] Sending acknowledgement for status update TASK_RUNNING (UUID: 554a8f0b-3c25-4647-abba-b2fcade3e962) for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 to executor(60)@172.17.0.2:46479
I0921 06:45:44.657222 31167 master.cpp:5638] Status update TASK_RUNNING (UUID: 554a8f0b-3c25-4647-abba-b2fcade3e962) for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 from agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 at slave(139)@172.17.0.2:46479 (maintenance-host-2)
I0921 06:45:44.657315 31167 master.cpp:5700] Forwarding status update TASK_RUNNING (UUID: 554a8f0b-3c25-4647-abba-b2fcade3e962) for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
I0921 06:45:44.657378 31165 exec.cpp:373] Executor received status update acknowledgement 554a8f0b-3c25-4647-abba-b2fcade3e962 for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
@ 0x7f873b733100 (unknown)
I0921 06:45:44.657830 31167 master.cpp:7537] Updating the state of task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0921 06:45:44.659090 31158 scheduler.cpp:666] Enqueuing event UPDATE received from http://172.17.0.2:46479/master/api/v1/scheduler
@ 0x7f873a76e4ed __GI_getenv
@ 0xa2022c os::getenv()
@ 0x7f873fedb079 mesos::internal::slave::executorEnvironment()
@ 0x7f873fed62cd mesos::internal::slave::Framework::launchExecutor()
@ 0x7f873feb3471 mesos::internal::slave::Slave::_run()
@ 0x7f873ff07c6f _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS_6FutureIbEERKNS1_13FrameworkInfoERKNS1_12ExecutorInfoERK6OptionINS1_8TaskInfoEERKSF_INS1_13TaskGroupInfoEES6_S9_SC_SH_SL_EEvRKNS_3PIDIT_EEMSP_FvT0_T1_T2_T3_T4_ET5_T6_T7_T8_T9_ENKUlPNS_11ProcessBaseEE_clES16_
@ 0x7f873ff3ba51 _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchIN5mesos8internal5slave5SlaveERKNS0_6FutureIbEERKNS5_13FrameworkInfoERKNS5_12ExecutorInfoERK6OptionINS5_8TaskInfoEERKSJ_INS5_13TaskGroupInfoEESA_SD_SG_SL_SP_EEvRKNS0_3PIDIT_EEMST_FvT0_T1_T2_T3_T4_ET5_T6_T7_T8_T9_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_
@ 0x7f8740ab0509 std::function<>::operator()()
@ 0x7f8740a92fcb process::ProcessBase::visit()
@ 0x7f8740a9b1d8 process::DispatchEvent::visit()
@ 0x9f46f6 process::ProcessBase::serve()
@ 0x7f8740a8f3c5 process::ProcessManager::resume()
@ 0x7f8740a8c0b3 _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv
@ 0x7f8740a9a956 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x7f8740a9a8ad _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv
@ 0x7f8740a9a846 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x7f873b0c5220 (unknown)
@ 0x7f873b72bdc5 start_thread
@ 0x7f873a82cced __clone
make[4]: *** [check-local] Segmentation fault
make[4]: Leaving directory `/mesos/mesos-1.1.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.1.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.1.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.1.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1474438562-28701
Untagged: mesos-1474438562-28701:latest
Deleted: sha256:c14a77c127a6d7718ffe848d2f529d7f2adbc403a2731e70a9fdb9976abac323
Deleted: sha256:a916a136878f565eb16ebe255a673cd1c9be5409f210d447d58a5f3555b326e5
Deleted: sha256:2e4e0df6de925367076550d965bfc8bd075c6ad750aaa4f7d8d36f0be9d46bfc
Deleted: sha256:2dcd13c4ca23640d0f5de24770a8aec33654c6dd120607077f9773c259f52ba0
Deleted: sha256:2cad874931ec0ce208b79ced81dc4a9a022bd3554c2b3d425fa26a7cc6676b21
Deleted: sha256:a3fe9244658dbc7b8e17ed58288cacac885160127c402984c6ea18fae6873fed
Deleted: sha256:78f19e0fb6619dbe54f5e3041609685bd00e4d657d4833aad2b22229edf84f4e
Deleted: sha256:ddef266c6807e9c9ea4518aaad17bd487436210db588fab06656c3e93b718bf6
Deleted: sha256:07124fd418bea773fa5c864c81cfa2c1ac1bf42351e90caa8458ad6bdb2357eb
Deleted: sha256:8d2117b3321995f1c8c525a1827431005703282310731bef4b94edecfaebd7ad
Deleted: sha256:1c9138895bf9ee4d2798ce238dd0867f71377c093e022a2c5fbe64df4a214bac
Deleted: sha256:7adc464e8c476d25b07daba0efaa1cd1ff03d82f3215219b00ea7839adafd416
Deleted: sha256:a9ab81e27d7507c573be49a7bfe574a3da024e0f1248b2f24dcf44bfa600213c
Deleted: sha256:ed4622dcda42522552996c0fd1e00546d26e0aa0007b6cab1dcc6e2775605611
Deleted: sha256:002bab597f6a300a1abf4819f73a51d64ec5f8810fef39bba1fb524130a33ca0
Deleted: sha256:bd6cd74c06310394bd49625ae17f50d2cbf4eac640856a45aac62fe6f5b4a122
Deleted: sha256:4feb2128c7abdd440042a3a86a024938ac71e7b4d8b0c3b97a86176138350169
Deleted: sha256:caa7691537e02d4109752bc4ca8269430d5bff6fb52377dc177b6443506d2e2f
Deleted: sha256:7e180587751ff084caae49c005306d583bb0e048cf93c0eb121ec9da9fa8c885
Deleted: sha256:9165e01b3dcc73cd031a20f86e1dd82a81d0b5e807c8d29ecded785275123a39
Deleted: sha256:f7d808738f4a5c4ae0215bf0e17ef73e76af64e8e9059e1217ea390a032043be
Deleted: sha256:4f8120cafd89a640bebd3b82182618971ce4c63ba945a65317d2257ac5315739
Deleted: sha256:6a9a60bf315e5e46585bb5f2c0f27f8a362054eaf331078639b522bc1d88f2b7
Deleted: sha256:3af059b95a5678f8d542879fe311911b6dbdc430f872b5036a80723d711c3e88
Deleted: sha256:b45d125476e0ff9fb194600d3114ce3cf6b14954cb5496e01b31c63b64abeb52
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6) #2659
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)/2659/changes>
Re: Build failed in Jenkins: Mesos » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6) #2658
Posted by Benjamin Bannier <be...@mesosphere.io>.
This looks a lot like an ::setenv and a ::getenv trampling on each others toes, filed https://issues.apache.org/jira/browse/MESOS-6216.
> On Sep 21, 2016, at 8:46 AM, Apache Jenkins Server <je...@builds.apache.org> wrote:
>
> See <https://builds.apache.org/job/Mesos/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)/2658/changes>
>
> Changes:
>
> [vinodkone] Missing `endl` in `cout` for default executor.
>
> ------------------------------------------
> [...truncated 71905 lines...]
> I0921 06:45:44.577169 31170 slave.cpp:1138] Checkpointing SlaveInfo to '/tmp/MasterMaintenanceTest_InverseOffersFilters_YUgj1b/meta/slaves/c77b2c37-1de7-4556-b6a3-616c186154b6-S0/slave.info'
> I0921 06:45:44.577531 31170 slave.cpp:1175] Forwarding total oversubscribed resources
> I0921 06:45:44.577697 31170 master.cpp:5508] Received update of agent c77b2c37-1de7-4556-b6a3-616c186154b6-S0 at slave(138)@172.17.0.2:46479 (maintenance-host) with total oversubscribed resources
> I0921 06:45:44.577782 31160 replica.cpp:691] Replica received learned notice for position 4 from @0.0.0.0:0
> I0921 06:45:44.577929 31160 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 116492ns
> I0921 06:45:44.577992 31160 leveldb.cpp:399] Deleting ~2 keys from leveldb took 38528ns
> I0921 06:45:44.577983 31170 hierarchical.cpp:546] Agent c77b2c37-1de7-4556-b6a3-616c186154b6-S0 (maintenance-host) updated with oversubscribed resources (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: )
> I0921 06:45:44.578019 31160 replica.cpp:708] Persisted action TRUNCATE at position 4
> I0921 06:45:44.578145 31170 hierarchical.cpp:1685] No allocations performed
> I0921 06:45:44.578208 31170 hierarchical.cpp:1300] Performed allocation for agent c77b2c37-1de7-4556-b6a3-616c186154b6-S0 in 180612ns
> I0921 06:45:44.578326 31162 status_update_manager.cpp:184] Resuming sending status updates
> I0921 06:45:44.581013 31138 cluster.cpp:435] Creating default 'local' authorizer
> I0921 06:45:44.582651 31168 slave.cpp:208] Mesos agent started on (139)@172.17.0.2:46479
> I0921 06:45:44.582672 31168 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/MasterMaintenanceTest_InverseOffersFilters_Adbvkm/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterMaintenanceTest_InverseOffersFilters_Adbvkm/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname="maintenance-host-2" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/MasterMaintenanceTest_InverseOffersFilters_Adbvkm/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.1.0/_build/src" --logbufsecs="0" --logging_level="INFO" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/MasterMaintenanceTest_InverseOffersFilters_Adbvkm" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/MasterMaintenanceTest_InverseOffersFilters_ICIYrV"
> I0921 06:45:44.583254 31168 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterMaintenanceTest_InverseOffersFilters_Adbvkm/credential'
> I0921 06:45:44.583430 31168 slave.cpp:346] Agent using credential for: test-principal
> I0921 06:45:44.583449 31168 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterMaintenanceTest_InverseOffersFilters_Adbvkm/http_credentials'
> I0921 06:45:44.583679 31168 http.cpp:883] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
> I0921 06:45:44.583851 31168 http.cpp:883] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
> I0921 06:45:44.585062 31168 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
> I0921 06:45:44.585166 31168 slave.cpp:541] Agent attributes: [ ]
> I0921 06:45:44.585183 31168 slave.cpp:546] Agent hostname: maintenance-host-2
> I0921 06:45:44.586913 31158 state.cpp:57] Recovering state from '/tmp/MasterMaintenanceTest_InverseOffersFilters_ICIYrV/meta'
> I0921 06:45:44.587239 31167 status_update_manager.cpp:203] Recovering status update manager
> I0921 06:45:44.587590 31166 slave.cpp:5227] Finished recovery
> I0921 06:45:44.588088 31166 slave.cpp:5399] Querying resource estimator for oversubscribable resources
> I0921 06:45:44.588416 31171 status_update_manager.cpp:177] Pausing sending status updates
> I0921 06:45:44.588418 31157 slave.cpp:915] New master detected at master@172.17.0.2:46479
> I0921 06:45:44.588462 31157 slave.cpp:974] Authenticating with master master@172.17.0.2:46479
> I0921 06:45:44.588534 31157 slave.cpp:985] Using default CRAM-MD5 authenticatee
> I0921 06:45:44.588685 31157 slave.cpp:947] Detecting new master
> I0921 06:45:44.588742 31163 authenticatee.cpp:121] Creating new client SASL connection
> I0921 06:45:44.588865 31157 slave.cpp:5413] Received oversubscribable resources from the resource estimator
> I0921 06:45:44.589001 31159 master.cpp:6583] Authenticating slave(139)@172.17.0.2:46479
> I0921 06:45:44.589109 31158 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(337)@172.17.0.2:46479
> I0921 06:45:44.589340 31169 authenticator.cpp:98] Creating new server SASL connection
> I0921 06:45:44.589545 31160 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
> I0921 06:45:44.589576 31160 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
> I0921 06:45:44.589684 31163 authenticator.cpp:204] Received SASL authentication start
> I0921 06:45:44.589741 31163 authenticator.cpp:326] Authentication requires more steps
> I0921 06:45:44.589872 31160 authenticatee.cpp:259] Received SASL authentication step
> I0921 06:45:44.590025 31166 authenticator.cpp:232] Received SASL authentication step
> I0921 06:45:44.590066 31166 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9b376cdf7066' server FQDN: '9b376cdf7066' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> I0921 06:45:44.590092 31166 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
> I0921 06:45:44.590126 31166 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
> I0921 06:45:44.590149 31166 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9b376cdf7066' server FQDN: '9b376cdf7066' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> I0921 06:45:44.590164 31166 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
> I0921 06:45:44.590175 31166 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> I0921 06:45:44.590193 31166 authenticator.cpp:318] Authentication success
> I0921 06:45:44.590294 31160 authenticatee.cpp:299] Authentication success
> I0921 06:45:44.590343 31171 master.cpp:6613] Successfully authenticated principal 'test-principal' at slave(139)@172.17.0.2:46479
> I0921 06:45:44.590405 31157 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(337)@172.17.0.2:46479
> I0921 06:45:44.590720 31165 slave.cpp:1069] Successfully authenticated with master master@172.17.0.2:46479
> I0921 06:45:44.590863 31165 slave.cpp:1475] Will retry registration in 12.326699ms if necessary
> I0921 06:45:44.591045 31164 master.cpp:5040] Registering agent at slave(139)@172.17.0.2:46479 (maintenance-host-2) with id c77b2c37-1de7-4556-b6a3-616c186154b6-S1
> I0921 06:45:44.591506 31159 registrar.cpp:461] Applied 1 operations in 67400ns; attempting to update the registry
> I0921 06:45:44.592420 31161 log.cpp:577] Attempting to append 513 bytes to the log
> I0921 06:45:44.592564 31171 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 5
> I0921 06:45:44.593293 31157 replica.cpp:537] Replica received write request for position 5 from __req_res__(2377)@172.17.0.2:46479
> I0921 06:45:44.593487 31157 leveldb.cpp:341] Persisting action (532 bytes) to leveldb took 153451ns
> I0921 06:45:44.593514 31157 replica.cpp:708] Persisted action APPEND at position 5
> I0921 06:45:44.594231 31169 replica.cpp:691] Replica received learned notice for position 5 from @0.0.0.0:0
> I0921 06:45:44.594650 31169 leveldb.cpp:341] Persisting action (534 bytes) to leveldb took 380554ns
> I0921 06:45:44.594677 31169 replica.cpp:708] Persisted action APPEND at position 5
> I0921 06:45:44.596431 31172 registrar.cpp:506] Successfully updated the registry in 4.856064ms
> I0921 06:45:44.596621 31164 log.cpp:596] Attempting to truncate the log to 5
> I0921 06:45:44.596762 31163 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 6
> I0921 06:45:44.597491 31164 slave.cpp:4089] Received ping from slave-observer(143)@172.17.0.2:46479
> I0921 06:45:44.597436 31170 master.cpp:5111] Registered agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 at slave(139)@172.17.0.2:46479 (maintenance-host-2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
> I0921 06:45:44.597842 31164 slave.cpp:1115] Registered with master master@172.17.0.2:46479; given agent ID c77b2c37-1de7-4556-b6a3-616c186154b6-S1
> I0921 06:45:44.597834 31163 hierarchical.cpp:482] Added agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 (maintenance-host-2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: )
> I0921 06:45:44.597867 31164 fetcher.cpp:86] Clearing fetcher cache
> I0921 06:45:44.598022 31168 replica.cpp:537] Replica received write request for position 6 from __req_res__(2378)@172.17.0.2:46479
> I0921 06:45:44.598031 31162 status_update_manager.cpp:184] Resuming sending status updates
> I0921 06:45:44.598047 31163 hierarchical.cpp:1685] No allocations performed
> I0921 06:45:44.598145 31163 hierarchical.cpp:1300] Performed allocation for agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 in 263331ns
> I0921 06:45:44.598223 31164 slave.cpp:1138] Checkpointing SlaveInfo to '/tmp/MasterMaintenanceTest_InverseOffersFilters_ICIYrV/meta/slaves/c77b2c37-1de7-4556-b6a3-616c186154b6-S1/slave.info'
> I0921 06:45:44.598517 31168 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 447921ns
> I0921 06:45:44.598542 31168 replica.cpp:708] Persisted action TRUNCATE at position 6
> I0921 06:45:44.598740 31164 slave.cpp:1175] Forwarding total oversubscribed resources
> I0921 06:45:44.599000 31160 master.cpp:5508] Received update of agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 at slave(139)@172.17.0.2:46479 (maintenance-host-2) with total oversubscribed resources
> I0921 06:45:44.599462 31164 replica.cpp:691] Replica received learned notice for position 6 from @0.0.0.0:0
> I0921 06:45:44.599454 31159 hierarchical.cpp:546] Agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 (maintenance-host-2) updated with oversubscribed resources (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: )
> I0921 06:45:44.599619 31159 hierarchical.cpp:1685] No allocations performed
> I0921 06:45:44.599629 31164 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 130163ns
> I0921 06:45:44.599684 31159 hierarchical.cpp:1300] Performed allocation for agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 in 175398ns
> I0921 06:45:44.599709 31164 leveldb.cpp:399] Deleting ~2 keys from leveldb took 50691ns
> I0921 06:45:44.599743 31164 replica.cpp:708] Persisted action TRUNCATE at position 6
> I0921 06:45:44.600493 31157 process.cpp:3336] Handling HTTP event for process 'master' with path: '/master/maintenance/schedule'
> I0921 06:45:44.601624 31164 http.cpp:382] HTTP POST for /master/maintenance/schedule from 172.17.0.2:32820
> I0921 06:45:44.602550 31169 registrar.cpp:461] Applied 1 operations in 166026ns; attempting to update the registry
> I0921 06:45:44.603627 31166 log.cpp:577] Attempting to append 732 bytes to the log
> I0921 06:45:44.603909 31171 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 7
> I0921 06:45:44.604677 31168 replica.cpp:537] Replica received write request for position 7 from __req_res__(2379)@172.17.0.2:46479
> I0921 06:45:44.604905 31168 leveldb.cpp:341] Persisting action (751 bytes) to leveldb took 178366ns
> I0921 06:45:44.604945 31168 replica.cpp:708] Persisted action APPEND at position 7
> I0921 06:45:44.605667 31163 replica.cpp:691] Replica received learned notice for position 7 from @0.0.0.0:0
> I0921 06:45:44.606122 31163 leveldb.cpp:341] Persisting action (753 bytes) to leveldb took 414315ns
> I0921 06:45:44.606153 31163 replica.cpp:708] Persisted action APPEND at position 7
> I0921 06:45:44.608824 31167 registrar.cpp:506] Successfully updated the registry in 6.203904ms
> I0921 06:45:44.609055 31162 log.cpp:596] Attempting to truncate the log to 7
> I0921 06:45:44.609230 31157 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 8
> I0921 06:45:44.609475 31165 master.cpp:5564] Updating unavailability of agent c77b2c37-1de7-4556-b6a3-616c186154b6-S0 at slave(138)@172.17.0.2:46479 (maintenance-host), starting at 2442.89813590989weeks
> I0921 06:45:44.609802 31165 master.cpp:5564] Updating unavailability of agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 at slave(139)@172.17.0.2:46479 (maintenance-host-2), starting at 2442.89813590989weeks
> I0921 06:45:44.610002 31157 hierarchical.cpp:1685] No allocations performed
> I0921 06:45:44.610070 31157 hierarchical.cpp:1300] Performed allocation for agent c77b2c37-1de7-4556-b6a3-616c186154b6-S0 in 232049ns
> I0921 06:45:44.610290 31157 hierarchical.cpp:1685] No allocations performed
> I0921 06:45:44.610352 31157 hierarchical.cpp:1300] Performed allocation for agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 in 173709ns
> I0921 06:45:44.610682 31157 replica.cpp:537] Replica received write request for position 8 from __req_res__(2380)@172.17.0.2:46479
> I0921 06:45:44.611059 31157 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 326846ns
> I0921 06:45:44.611090 31157 replica.cpp:708] Persisted action TRUNCATE at position 8
> I0921 06:45:44.611979 31158 replica.cpp:691] Replica received learned notice for position 8 from @0.0.0.0:0
> I0921 06:45:44.612287 31138 scheduler.cpp:176] Version: 1.1.0
> I0921 06:45:44.612345 31158 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 337868ns
> I0921 06:45:44.612414 31158 leveldb.cpp:399] Deleting ~2 keys from leveldb took 45428ns
> I0921 06:45:44.612432 31158 replica.cpp:708] Persisted action TRUNCATE at position 8
> I0921 06:45:44.612915 31166 scheduler.cpp:465] New master detected at master@172.17.0.2:46479
> I0921 06:45:44.612944 31166 scheduler.cpp:474] Waiting for 0ns before initiating a re-(connection) attempt with the master
> I0921 06:45:44.614563 31172 scheduler.cpp:353] Connected with the master at http://172.17.0.2:46479/master/api/v1/scheduler
> I0921 06:45:44.615628 31157 scheduler.cpp:235] Sending SUBSCRIBE call to http://172.17.0.2:46479/master/api/v1/scheduler
> I0921 06:45:44.616323 31162 process.cpp:3336] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
> I0921 06:45:44.617158 31158 http.cpp:382] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:32822
> I0921 06:45:44.617336 31158 master.cpp:2309] Received subscription request for HTTP framework 'default'
> I0921 06:45:44.617389 31158 master.cpp:2049] Authorizing framework principal 'test-principal' to receive offers for role '*'
> I0921 06:45:44.617753 31165 master.cpp:2407] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
> I0921 06:45:44.618355 31161 hierarchical.cpp:272] Added framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
> I0921 06:45:44.618464 31162 master.hpp:2162] Sending heartbeat to c77b2c37-1de7-4556-b6a3-616c186154b6-0000
> I0921 06:45:44.619231 31167 scheduler.cpp:666] Enqueuing event SUBSCRIBED received from http://172.17.0.2:46479/master/api/v1/scheduler
> I0921 06:45:44.619892 31167 scheduler.cpp:666] Enqueuing event HEARTBEAT received from http://172.17.0.2:46479/master/api/v1/scheduler
> I0921 06:45:44.620586 31171 master_maintenance_tests.cpp:175] Ignoring HEARTBEAT event
> I0921 06:45:44.620697 31161 hierarchical.cpp:1277] Performed allocation for 2 agents in 2.310674ms
> I0921 06:45:44.621461 31172 master.cpp:6412] Sending 2 offers to framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 (default)
> I0921 06:45:44.622498 31172 master.cpp:6502] Sending 2 inverse offers to framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 (default)
> I0921 06:45:44.624071 31157 scheduler.cpp:666] Enqueuing event OFFERS received from http://172.17.0.2:46479/master/api/v1/scheduler
> I0921 06:45:44.625038 31157 scheduler.cpp:666] Enqueuing event INVERSE_OFFERS received from http://172.17.0.2:46479/master/api/v1/scheduler
> I0921 06:45:44.626667 31160 hierarchical.cpp:1685] No allocations performed
> I0921 06:45:44.626986 31160 hierarchical.cpp:1277] Performed allocation for 2 agents in 449517ns
> I0921 06:45:44.628167 31171 scheduler.cpp:235] Sending ACCEPT call to http://172.17.0.2:46479/master/api/v1/scheduler
> I0921 06:45:44.628940 31171 scheduler.cpp:235] Sending ACCEPT call to http://172.17.0.2:46479/master/api/v1/scheduler
> I0921 06:45:44.629108 31158 process.cpp:3336] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
> I0921 06:45:44.629750 31171 process.cpp:3336] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
> I0921 06:45:44.630563 31160 http.cpp:382] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:32821
> I0921 06:45:44.631300 31160 master.cpp:3521] Processing ACCEPT call for offers: [ c77b2c37-1de7-4556-b6a3-616c186154b6-O0 ] on agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 at slave(139)@172.17.0.2:46479 (maintenance-host-2) for framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 (default)
> I0921 06:45:44.631395 31160 master.cpp:3143] Authorizing framework principal 'test-principal' to launch task e133c1d9-394b-45b7-9a78-90a87b31d7ce
> I0921 06:45:44.632381 31160 http.cpp:382] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:32821
> I0921 06:45:44.633518 31160 master.cpp:3521] Processing ACCEPT call for offers: [ c77b2c37-1de7-4556-b6a3-616c186154b6-O1 ] on agent c77b2c37-1de7-4556-b6a3-616c186154b6-S0 at slave(138)@172.17.0.2:46479 (maintenance-host) for framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 (default)
> I0921 06:45:44.633666 31160 master.cpp:3143] Authorizing framework principal 'test-principal' to launch task ff0ce124-f019-4c0c-90a5-a5ff04a7c2d8
> W0921 06:45:44.636899 31160 validation.cpp:916] Executor 'executor-1' for task 'e133c1d9-394b-45b7-9a78-90a87b31d7ce' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
> W0921 06:45:44.636947 31160 validation.cpp:928] Executor 'executor-1' for task 'e133c1d9-394b-45b7-9a78-90a87b31d7ce' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
> I0921 06:45:44.637576 31160 master.cpp:8159] Adding task e133c1d9-394b-45b7-9a78-90a87b31d7ce with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 (maintenance-host-2)
> I0921 06:45:44.637992 31160 master.cpp:4121] Launching task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 (default) with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 at slave(139)@172.17.0.2:46479 (maintenance-host-2)
> I0921 06:45:44.638564 31171 slave.cpp:1539] Got assigned task 'e133c1d9-394b-45b7-9a78-90a87b31d7ce' for framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
> I0921 06:45:44.639427 31171 slave.cpp:1696] Launching task 'e133c1d9-394b-45b7-9a78-90a87b31d7ce' for framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
> W0921 06:45:44.639868 31160 validation.cpp:916] Executor 'executor-2' for task 'ff0ce124-f019-4c0c-90a5-a5ff04a7c2d8' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
> W0921 06:45:44.639906 31160 validation.cpp:928] Executor 'executor-2' for task 'ff0ce124-f019-4c0c-90a5-a5ff04a7c2d8' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
> I0921 06:45:44.640231 31171 paths.cpp:536] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_ICIYrV/slaves/c77b2c37-1de7-4556-b6a3-616c186154b6-S1/frameworks/c77b2c37-1de7-4556-b6a3-616c186154b6-0000/executors/executor-1/runs/11d8af52-90ce-45e8-80bb-6668bc654f18' to user 'mesos'
> I0921 06:45:44.640296 31160 master.cpp:8159] Adding task ff0ce124-f019-4c0c-90a5-a5ff04a7c2d8 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent c77b2c37-1de7-4556-b6a3-616c186154b6-S0 (maintenance-host)
> I0921 06:45:44.643380 31160 master.cpp:4121] Launching task ff0ce124-f019-4c0c-90a5-a5ff04a7c2d8 of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 (default) with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent c77b2c37-1de7-4556-b6a3-616c186154b6-S0 at slave(138)@172.17.0.2:46479 (maintenance-host)
> I0921 06:45:44.644194 31162 slave.cpp:1539] Got assigned task 'ff0ce124-f019-4c0c-90a5-a5ff04a7c2d8' for framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
> I0921 06:45:44.644804 31162 slave.cpp:1696] Launching task 'ff0ce124-f019-4c0c-90a5-a5ff04a7c2d8' for framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
> I0921 06:45:44.645376 31162 paths.cpp:536] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_YUgj1b/slaves/c77b2c37-1de7-4556-b6a3-616c186154b6-S0/frameworks/c77b2c37-1de7-4556-b6a3-616c186154b6-0000/executors/executor-2/runs/4f76d764-b84b-4e4b-831c-ef85e72fac9c' to user 'mesos'
> I0921 06:45:44.649134 31171 slave.cpp:6110] Launching executor 'executor-1' of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 with resources in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_ICIYrV/slaves/c77b2c37-1de7-4556-b6a3-616c186154b6-S1/frameworks/c77b2c37-1de7-4556-b6a3-616c186154b6-0000/executors/executor-1/runs/11d8af52-90ce-45e8-80bb-6668bc654f18'
> I0921 06:45:44.651036 31162 slave.cpp:6110] Launching executor 'executor-2' of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 with resources in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_YUgj1b/slaves/c77b2c37-1de7-4556-b6a3-616c186154b6-S0/frameworks/c77b2c37-1de7-4556-b6a3-616c186154b6-0000/executors/executor-2/runs/4f76d764-b84b-4e4b-831c-ef85e72fac9c'
> *** Aborted at 1474440344 (unix time) try "date -d @1474440344" if you are using GNU date ***
> I0921 06:45:44.651903 31171 exec.cpp:162] Version: 1.1.0
> I0921 06:45:44.652312 31157 exec.cpp:212] Executor started at: executor(60)@172.17.0.2:46479 with pid 31138
> I0921 06:45:44.652678 31171 slave.cpp:1982] Queued task 'e133c1d9-394b-45b7-9a78-90a87b31d7ce' for executor 'executor-1' of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
> I0921 06:45:44.652762 31171 slave.cpp:868] Successfully attached file '/tmp/MasterMaintenanceTest_InverseOffersFilters_ICIYrV/slaves/c77b2c37-1de7-4556-b6a3-616c186154b6-S1/frameworks/c77b2c37-1de7-4556-b6a3-616c186154b6-0000/executors/executor-1/runs/11d8af52-90ce-45e8-80bb-6668bc654f18'
> I0921 06:45:44.652858 31171 slave.cpp:3203] Got registration for executor 'executor-1' of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 from executor(60)@172.17.0.2:46479
> PC: @ 0x7f873a76e4ed __GI_getenv
> I0921 06:45:44.653328 31164 exec.cpp:237] Executor registered on agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1
> I0921 06:45:44.653384 31164 exec.cpp:249] Executor::registered took 30036ns
> *** SIGSEGV (@0x20) received by PID 31138 (TID 0x7f8730711700) from PID 32; stack trace: ***
> I0921 06:45:44.654062 31171 slave.cpp:2186] Sending queued task 'e133c1d9-394b-45b7-9a78-90a87b31d7ce' to executor 'executor-1' of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 at executor(60)@172.17.0.2:46479
> I0921 06:45:44.654408 31159 exec.cpp:324] Executor asked to run task 'e133c1d9-394b-45b7-9a78-90a87b31d7ce'
> @ 0x7f8728e3f2f5 (unknown)
> I0921 06:45:44.654507 31159 exec.cpp:333] Executor::launchTask took 74492ns
> I0921 06:45:44.654623 31159 exec.cpp:550] Executor sending status update TASK_RUNNING (UUID: 554a8f0b-3c25-4647-abba-b2fcade3e962) for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
> I0921 06:45:44.654983 31160 slave.cpp:3606] Handling status update TASK_RUNNING (UUID: 554a8f0b-3c25-4647-abba-b2fcade3e962) for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 from executor(60)@172.17.0.2:46479
> @ 0x7f8728e43ec1 (unknown)
> I0921 06:45:44.655642 31159 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 554a8f0b-3c25-4647-abba-b2fcade3e962) for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
> I0921 06:45:44.655690 31159 status_update_manager.cpp:500] Creating StatusUpdate stream for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
> @ 0x7f8728e381b8 (unknown)
> I0921 06:45:44.656296 31159 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 554a8f0b-3c25-4647-abba-b2fcade3e962) for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 to the agent
> I0921 06:45:44.656617 31170 slave.cpp:4007] Forwarding the update TASK_RUNNING (UUID: 554a8f0b-3c25-4647-abba-b2fcade3e962) for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 to master@172.17.0.2:46479
> I0921 06:45:44.656970 31170 slave.cpp:3901] Status update manager successfully handled status update TASK_RUNNING (UUID: 554a8f0b-3c25-4647-abba-b2fcade3e962) for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
> I0921 06:45:44.657042 31170 slave.cpp:3917] Sending acknowledgement for status update TASK_RUNNING (UUID: 554a8f0b-3c25-4647-abba-b2fcade3e962) for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 to executor(60)@172.17.0.2:46479
> I0921 06:45:44.657222 31167 master.cpp:5638] Status update TASK_RUNNING (UUID: 554a8f0b-3c25-4647-abba-b2fcade3e962) for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 from agent c77b2c37-1de7-4556-b6a3-616c186154b6-S1 at slave(139)@172.17.0.2:46479 (maintenance-host-2)
> I0921 06:45:44.657315 31167 master.cpp:5700] Forwarding status update TASK_RUNNING (UUID: 554a8f0b-3c25-4647-abba-b2fcade3e962) for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
> I0921 06:45:44.657378 31165 exec.cpp:373] Executor received status update acknowledgement 554a8f0b-3c25-4647-abba-b2fcade3e962 for task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000
> @ 0x7f873b733100 (unknown)
> I0921 06:45:44.657830 31167 master.cpp:7537] Updating the state of task e133c1d9-394b-45b7-9a78-90a87b31d7ce of framework c77b2c37-1de7-4556-b6a3-616c186154b6-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
> I0921 06:45:44.659090 31158 scheduler.cpp:666] Enqueuing event UPDATE received from http://172.17.0.2:46479/master/api/v1/scheduler
> @ 0x7f873a76e4ed __GI_getenv
> @ 0xa2022c os::getenv()
> @ 0x7f873fedb079 mesos::internal::slave::executorEnvironment()
> @ 0x7f873fed62cd mesos::internal::slave::Framework::launchExecutor()
> @ 0x7f873feb3471 mesos::internal::slave::Slave::_run()
> @ 0x7f873ff07c6f _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS_6FutureIbEERKNS1_13FrameworkInfoERKNS1_12ExecutorInfoERK6OptionINS1_8TaskInfoEERKSF_INS1_13TaskGroupInfoEES6_S9_SC_SH_SL_EEvRKNS_3PIDIT_EEMSP_FvT0_T1_T2_T3_T4_ET5_T6_T7_T8_T9_ENKUlPNS_11ProcessBaseEE_clES16_
> @ 0x7f873ff3ba51 _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchIN5mesos8internal5slave5SlaveERKNS0_6FutureIbEERKNS5_13FrameworkInfoERKNS5_12ExecutorInfoERK6OptionINS5_8TaskInfoEERKSJ_INS5_13TaskGroupInfoEESA_SD_SG_SL_SP_EEvRKNS0_3PIDIT_EEMST_FvT0_T1_T2_T3_T4_ET5_T6_T7_T8_T9_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_
> @ 0x7f8740ab0509 std::function<>::operator()()
> @ 0x7f8740a92fcb process::ProcessBase::visit()
> @ 0x7f8740a9b1d8 process::DispatchEvent::visit()
> @ 0x9f46f6 process::ProcessBase::serve()
> @ 0x7f8740a8f3c5 process::ProcessManager::resume()
> @ 0x7f8740a8c0b3 _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv
> @ 0x7f8740a9a956 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
> @ 0x7f8740a9a8ad _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv
> @ 0x7f8740a9a846 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
> @ 0x7f873b0c5220 (unknown)
> @ 0x7f873b72bdc5 start_thread
> @ 0x7f873a82cced __clone
> make[4]: *** [check-local] Segmentation fault
> make[4]: Leaving directory `/mesos/mesos-1.1.0/_build/src'
> make[3]: *** [check-am] Error 2
> make[3]: Leaving directory `/mesos/mesos-1.1.0/_build/src'
> make[2]: *** [check] Error 2
> make[2]: Leaving directory `/mesos/mesos-1.1.0/_build/src'
> make[1]: *** [check-recursive] Error 1
> make[1]: Leaving directory `/mesos/mesos-1.1.0/_build'
> make: *** [distcheck] Error 1
> + docker rmi mesos-1474438562-28701
> Untagged: mesos-1474438562-28701:latest
> Deleted: sha256:c14a77c127a6d7718ffe848d2f529d7f2adbc403a2731e70a9fdb9976abac323
> Deleted: sha256:a916a136878f565eb16ebe255a673cd1c9be5409f210d447d58a5f3555b326e5
> Deleted: sha256:2e4e0df6de925367076550d965bfc8bd075c6ad750aaa4f7d8d36f0be9d46bfc
> Deleted: sha256:2dcd13c4ca23640d0f5de24770a8aec33654c6dd120607077f9773c259f52ba0
> Deleted: sha256:2cad874931ec0ce208b79ced81dc4a9a022bd3554c2b3d425fa26a7cc6676b21
> Deleted: sha256:a3fe9244658dbc7b8e17ed58288cacac885160127c402984c6ea18fae6873fed
> Deleted: sha256:78f19e0fb6619dbe54f5e3041609685bd00e4d657d4833aad2b22229edf84f4e
> Deleted: sha256:ddef266c6807e9c9ea4518aaad17bd487436210db588fab06656c3e93b718bf6
> Deleted: sha256:07124fd418bea773fa5c864c81cfa2c1ac1bf42351e90caa8458ad6bdb2357eb
> Deleted: sha256:8d2117b3321995f1c8c525a1827431005703282310731bef4b94edecfaebd7ad
> Deleted: sha256:1c9138895bf9ee4d2798ce238dd0867f71377c093e022a2c5fbe64df4a214bac
> Deleted: sha256:7adc464e8c476d25b07daba0efaa1cd1ff03d82f3215219b00ea7839adafd416
> Deleted: sha256:a9ab81e27d7507c573be49a7bfe574a3da024e0f1248b2f24dcf44bfa600213c
> Deleted: sha256:ed4622dcda42522552996c0fd1e00546d26e0aa0007b6cab1dcc6e2775605611
> Deleted: sha256:002bab597f6a300a1abf4819f73a51d64ec5f8810fef39bba1fb524130a33ca0
> Deleted: sha256:bd6cd74c06310394bd49625ae17f50d2cbf4eac640856a45aac62fe6f5b4a122
> Deleted: sha256:4feb2128c7abdd440042a3a86a024938ac71e7b4d8b0c3b97a86176138350169
> Deleted: sha256:caa7691537e02d4109752bc4ca8269430d5bff6fb52377dc177b6443506d2e2f
> Deleted: sha256:7e180587751ff084caae49c005306d583bb0e048cf93c0eb121ec9da9fa8c885
> Deleted: sha256:9165e01b3dcc73cd031a20f86e1dd82a81d0b5e807c8d29ecded785275123a39
> Deleted: sha256:f7d808738f4a5c4ae0215bf0e17ef73e76af64e8e9059e1217ea390a032043be
> Deleted: sha256:4f8120cafd89a640bebd3b82182618971ce4c63ba945a65317d2257ac5315739
> Deleted: sha256:6a9a60bf315e5e46585bb5f2c0f27f8a362054eaf331078639b522bc1d88f2b7
> Deleted: sha256:3af059b95a5678f8d542879fe311911b6dbdc430f872b5036a80723d711c3e88
> Deleted: sha256:b45d125476e0ff9fb194600d3114ce3cf6b14954cb5496e01b31c63b64abeb52
> Build step 'Execute shell' marked build as failure