You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2016/03/31 19:09:48 UTC
Build failed in Jenkins: Mesos » clang,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1914
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1914/changes>
Changes:
[yujie.jay] Add JSON::Object::at() helper API.
[yujie.jay] Fix the local Docker puller for private registries.
------------------------------------------
[...truncated 136807 lines...]
I0331 17:09:45.320572 1317 master.cpp:427] Master only allowing authenticated frameworks to register
I0331 17:09:45.320585 1317 master.cpp:432] Master only allowing authenticated slaves to register
I0331 17:09:45.320595 1317 credentials.hpp:37] Loading credentials for authentication from '/tmp/HoiV0t/credentials'
I0331 17:09:45.321111 1317 master.cpp:474] Using default 'crammd5' authenticator
I0331 17:09:45.321318 1317 master.cpp:545] Using default 'basic' HTTP authenticator
I0331 17:09:45.321517 1317 master.cpp:583] Authorization enabled
I0331 17:09:45.321770 1325 hierarchical.cpp:144] Initialized hierarchical allocator process
I0331 17:09:45.321846 1325 whitelist_watcher.cpp:77] No whitelist given
I0331 17:09:45.325551 1317 master.cpp:1826] The newly elected leader is master@172.17.0.2:47264 with id 9b06f6db-72f1-42be-b262-98eb1ddb86e2
I0331 17:09:45.325592 1317 master.cpp:1839] Elected as the leading master!
I0331 17:09:45.325604 1317 master.cpp:1526] Recovering from registrar
I0331 17:09:45.325875 1331 registrar.cpp:307] Recovering registrar
I0331 17:09:45.326637 1331 log.cpp:659] Attempting to start the writer
I0331 17:09:45.328130 1324 replica.cpp:493] Replica received implicit promise request from (12032)@172.17.0.2:47264 with proposal 1
I0331 17:09:45.328989 1324 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 832457ns
I0331 17:09:45.329020 1324 replica.cpp:342] Persisted promised to 1
I0331 17:09:45.329779 1329 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0331 17:09:45.332118 1329 replica.cpp:388] Replica received explicit promise request from (12033)@172.17.0.2:47264 for position 0 with proposal 2
I0331 17:09:45.332484 1329 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 333132ns
I0331 17:09:45.332510 1329 replica.cpp:712] Persisted action at 0
I0331 17:09:45.333724 1318 replica.cpp:537] Replica received write request for position 0 from (12034)@172.17.0.2:47264
I0331 17:09:45.333792 1318 leveldb.cpp:436] Reading position from leveldb took 45226ns
I0331 17:09:45.334182 1318 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 358530ns
I0331 17:09:45.334213 1318 replica.cpp:712] Persisted action at 0
I0331 17:09:45.335052 1318 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0331 17:09:45.335985 1318 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 920454ns
I0331 17:09:45.336016 1318 replica.cpp:712] Persisted action at 0
I0331 17:09:45.336040 1318 replica.cpp:697] Replica learned NOP action at position 0
I0331 17:09:45.336995 1327 log.cpp:675] Writer started with ending position 0
I0331 17:09:45.338444 1319 leveldb.cpp:436] Reading position from leveldb took 66714ns
I0331 17:09:45.339751 1323 registrar.cpp:340] Successfully fetched the registry (0B) in 13.831936ms
I0331 17:09:45.339889 1323 registrar.cpp:439] Applied 1 operations in 45178ns; attempting to update the 'registry'
I0331 17:09:45.340608 1325 log.cpp:683] Attempting to append 170 bytes to the log
I0331 17:09:45.340701 1319 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0331 17:09:45.341581 1320 replica.cpp:537] Replica received write request for position 1 from (12035)@172.17.0.2:47264
I0331 17:09:45.342003 1320 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 395996ns
I0331 17:09:45.342036 1320 replica.cpp:712] Persisted action at 1
I0331 17:09:45.343068 1320 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0331 17:09:45.343983 1320 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 781249ns
I0331 17:09:45.344081 1320 replica.cpp:712] Persisted action at 1
I0331 17:09:45.344189 1320 replica.cpp:697] Replica learned APPEND action at position 1
I0331 17:09:45.345816 1320 registrar.cpp:484] Successfully updated the 'registry' in 5.862144ms
I0331 17:09:45.346009 1319 log.cpp:702] Attempting to truncate the log to 1
I0331 17:09:45.346058 1320 registrar.cpp:370] Successfully recovered registrar
I0331 17:09:45.346191 1319 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0331 17:09:45.347513 1317 replica.cpp:537] Replica received write request for position 2 from (12036)@172.17.0.2:47264
I0331 17:09:45.347892 1320 master.cpp:1634] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0331 17:09:45.347992 1317 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 434355ns
I0331 17:09:45.348021 1317 replica.cpp:712] Persisted action at 2
I0331 17:09:45.348026 1323 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0331 17:09:45.348672 1326 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0331 17:09:45.349053 1326 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 362152ns
I0331 17:09:45.349113 1326 leveldb.cpp:399] Deleting ~1 keys from leveldb took 44449ns
I0331 17:09:45.349138 1326 replica.cpp:712] Persisted action at 2
I0331 17:09:45.349161 1326 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0331 17:09:45.364617 1331 slave.cpp:200] Slave started on 357)@172.17.0.2:47264
I0331 17:09:45.364680 1331 slave.cpp:201] Flags at startup: --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http="true" --authenticatee="crammd5" --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/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/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" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_credentials="/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher_dir="/mesos/mesos-0.29.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;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --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/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi"
I0331 17:09:45.365267 1331 credentials.hpp:86] Loading credential for authentication from '/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/credential'
I0331 17:09:45.365537 1331 slave.cpp:338] Slave using credential for: test-principal
I0331 17:09:45.365581 1331 credentials.hpp:37] Loading credentials for authentication from '/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/http_credentials'
I0331 17:09:45.365871 1331 slave.cpp:390] Using default 'basic' HTTP authenticator
I0331 17:09:45.366381 1331 resources.cpp:572] Parsing resources as JSON failed: cpus:2;mem:1024;disk:1024;ports:[31000-32000]
Trying semicolon-delimited string format instead
I0331 17:09:45.366503 1299 sched.cpp:222] Version: 0.29.0
I0331 17:09:45.366998 1331 slave.cpp:589] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0331 17:09:45.367086 1331 slave.cpp:597] Slave attributes: [ ]
I0331 17:09:45.367101 1331 slave.cpp:602] Slave hostname: 29ba6f7aca97
I0331 17:09:45.367518 1321 sched.cpp:326] New master detected at master@172.17.0.2:47264
I0331 17:09:45.367595 1321 sched.cpp:382] Authenticating with master master@172.17.0.2:47264
I0331 17:09:45.367617 1321 sched.cpp:389] Using default CRAM-MD5 authenticatee
I0331 17:09:45.367931 1321 authenticatee.cpp:121] Creating new client SASL connection
I0331 17:09:45.368347 1321 master.cpp:5679] Authenticating scheduler-750dafdb-b99a-49b6-883c-3de70916a485@172.17.0.2:47264
I0331 17:09:45.368533 1321 authenticator.cpp:413] Starting authentication session for crammd5_authenticatee(753)@172.17.0.2:47264
I0331 17:09:45.368839 1321 authenticator.cpp:98] Creating new server SASL connection
I0331 17:09:45.369026 1317 state.cpp:57] Recovering state from '/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/meta'
I0331 17:09:45.369580 1317 authenticatee.cpp:212] Received SASL authentication mechanisms: CRAM-MD5
I0331 17:09:45.369616 1317 authenticatee.cpp:238] Attempting to authenticate with mechanism 'CRAM-MD5'
I0331 17:09:45.369716 1321 authenticator.cpp:203] Received SASL authentication start
I0331 17:09:45.369781 1321 authenticator.cpp:325] Authentication requires more steps
I0331 17:09:45.369848 1320 status_update_manager.cpp:200] Recovering status update manager
I0331 17:09:45.369868 1321 authenticatee.cpp:258] Received SASL authentication step
I0331 17:09:45.370017 1320 authenticator.cpp:231] Received SASL authentication step
I0331 17:09:45.370057 1320 auxprop.cpp:107] Request to lookup properties for user: 'test-principal' realm: '29ba6f7aca97' server FQDN: '29ba6f7aca97' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0331 17:09:45.370074 1320 auxprop.cpp:179] Looking up auxiliary property '*userPassword'
I0331 17:09:45.370127 1320 auxprop.cpp:179] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0331 17:09:45.370151 1321 slave.cpp:4773] Finished recovery
I0331 17:09:45.370156 1320 auxprop.cpp:107] Request to lookup properties for user: 'test-principal' realm: '29ba6f7aca97' server FQDN: '29ba6f7aca97' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0331 17:09:45.370182 1320 auxprop.cpp:129] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0331 17:09:45.370193 1320 auxprop.cpp:129] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0331 17:09:45.370211 1320 authenticator.cpp:317] Authentication success
I0331 17:09:45.370369 1320 authenticatee.cpp:298] Authentication success
I0331 17:09:45.370455 1320 master.cpp:5709] Successfully authenticated principal 'test-principal' at scheduler-750dafdb-b99a-49b6-883c-3de70916a485@172.17.0.2:47264
I0331 17:09:45.370546 1320 authenticator.cpp:431] Authentication session cleanup for crammd5_authenticatee(753)@172.17.0.2:47264
I0331 17:09:45.370789 1321 slave.cpp:4945] Querying resource estimator for oversubscribable resources
I0331 17:09:45.371309 1321 status_update_manager.cpp:174] Pausing sending status updates
I0331 17:09:45.371325 1326 slave.cpp:928] New master detected at master@172.17.0.2:47264
I0331 17:09:45.371408 1326 slave.cpp:991] Authenticating with master master@172.17.0.2:47264
I0331 17:09:45.371428 1326 slave.cpp:996] Using default CRAM-MD5 authenticatee
I0331 17:09:45.371578 1326 slave.cpp:964] Detecting new master
I0331 17:09:45.371677 1321 authenticatee.cpp:121] Creating new client SASL connection
I0331 17:09:45.372030 1317 master.cpp:5679] Authenticating slave(357)@172.17.0.2:47264
I0331 17:09:45.372042 1330 slave.cpp:4959] Received oversubscribable resources from the resource estimator
I0331 17:09:45.372134 1330 authenticator.cpp:413] Starting authentication session for crammd5_authenticatee(754)@172.17.0.2:47264
I0331 17:09:45.372319 1322 authenticator.cpp:98] Creating new server SASL connection
I0331 17:09:45.372548 1321 authenticatee.cpp:212] Received SASL authentication mechanisms: CRAM-MD5
I0331 17:09:45.372582 1321 authenticatee.cpp:238] Attempting to authenticate with mechanism 'CRAM-MD5'
I0331 17:09:45.372684 1322 authenticator.cpp:203] Received SASL authentication start
I0331 17:09:45.372746 1322 authenticator.cpp:325] Authentication requires more steps
I0331 17:09:45.372835 1322 authenticatee.cpp:258] Received SASL authentication step
I0331 17:09:45.372928 1322 authenticator.cpp:231] Received SASL authentication step
I0331 17:09:45.373037 1322 auxprop.cpp:107] Request to lookup properties for user: 'test-principal' realm: '29ba6f7aca97' server FQDN: '29ba6f7aca97' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0331 17:09:45.373059 1322 auxprop.cpp:179] Looking up auxiliary property '*userPassword'
I0331 17:09:45.373107 1322 auxprop.cpp:179] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0331 17:09:45.373134 1322 auxprop.cpp:107] Request to lookup properties for user: 'test-principal' realm: '29ba6f7aca97' server FQDN: '29ba6f7aca97' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0331 17:09:45.373147 1322 auxprop.cpp:129] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0331 17:09:45.373155 1322 auxprop.cpp:129] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0331 17:09:45.373173 1322 authenticator.cpp:317] Authentication success
I0331 17:09:45.373255 1320 authenticatee.cpp:298] Authentication success
I0331 17:09:45.373327 1322 master.cpp:5709] Successfully authenticated principal 'test-principal' at slave(357)@172.17.0.2:47264
I0331 17:09:45.373345 1320 authenticator.cpp:431] Authentication session cleanup for crammd5_authenticatee(754)@172.17.0.2:47264
I0331 17:09:45.373495 1329 sched.cpp:472] Successfully authenticated with master master@172.17.0.2:47264
I0331 17:09:45.373517 1329 sched.cpp:777] Sending SUBSCRIBE call to master@172.17.0.2:47264
I0331 17:09:45.373615 1329 sched.cpp:810] Will retry registration in 1.487722594secs if necessary
I0331 17:09:45.373731 1320 master.cpp:2346] Received SUBSCRIBE call for framework 'default' at scheduler-750dafdb-b99a-49b6-883c-3de70916a485@172.17.0.2:47264
I0331 17:09:45.373754 1320 master.cpp:1865] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0331 17:09:45.374069 1320 master.cpp:2417] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0331 17:09:45.374737 1320 hierarchical.cpp:266] Added framework 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000
I0331 17:09:45.374830 1320 hierarchical.cpp:1490] No resources available to allocate!
I0331 17:09:45.374868 1320 hierarchical.cpp:1585] No inverse offers to send out!
I0331 17:09:45.374927 1320 hierarchical.cpp:1141] Performed allocation for 0 slaves in 176144ns
I0331 17:09:45.375205 1317 sched.cpp:704] Framework registered with 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000
I0331 17:09:45.375283 1317 sched.cpp:718] Scheduler::registered took 32966ns
I0331 17:09:45.375393 1322 slave.cpp:1061] Successfully authenticated with master master@172.17.0.2:47264
I0331 17:09:45.375533 1322 slave.cpp:1457] Will retry registration in 15.182386ms if necessary
I0331 17:09:45.375825 1322 master.cpp:4390] Registering slave at slave(357)@172.17.0.2:47264 (29ba6f7aca97) with id 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0
I0331 17:09:45.376332 1320 registrar.cpp:439] Applied 1 operations in 81112ns; attempting to update the 'registry'
I0331 17:09:45.377719 1322 log.cpp:683] Attempting to append 339 bytes to the log
I0331 17:09:45.377935 1322 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 3
I0331 17:09:45.379067 1330 replica.cpp:537] Replica received write request for position 3 from (12042)@172.17.0.2:47264
I0331 17:09:45.379992 1330 leveldb.cpp:341] Persisting action (358 bytes) to leveldb took 884750ns
I0331 17:09:45.380023 1330 replica.cpp:712] Persisted action at 3
I0331 17:09:45.381299 1330 replica.cpp:691] Replica received learned notice for position 3 from @0.0.0.0:0
I0331 17:09:45.381980 1330 leveldb.cpp:341] Persisting action (360 bytes) to leveldb took 604022ns
I0331 17:09:45.382009 1330 replica.cpp:712] Persisted action at 3
I0331 17:09:45.382030 1330 replica.cpp:697] Replica learned APPEND action at position 3
I0331 17:09:45.391926 1321 registrar.cpp:484] Successfully updated the 'registry' in 15520us
I0331 17:09:45.392407 1322 log.cpp:702] Attempting to truncate the log to 3
I0331 17:09:45.392673 1322 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 4
I0331 17:09:45.392875 1321 master.cpp:4458] Registered slave 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 at slave(357)@172.17.0.2:47264 (29ba6f7aca97) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0331 17:09:45.393252 1321 hierarchical.cpp:476] Added slave 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 (29ba6f7aca97) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: )
I0331 17:09:45.393406 1317 slave.cpp:1105] Registered with master master@172.17.0.2:47264; given slave ID 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0
I0331 17:09:45.393548 1317 fetcher.cpp:81] Clearing fetcher cache
I0331 17:09:45.394038 1319 status_update_manager.cpp:181] Resuming sending status updates
I0331 17:09:45.394340 1317 slave.cpp:1128] Checkpointing SlaveInfo to '/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/meta/slaves/9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0/slave.info'
I0331 17:09:45.394834 1325 master.cpp:5508] Sending 1 offers to framework 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000 (default) at scheduler-750dafdb-b99a-49b6-883c-3de70916a485@172.17.0.2:47264
I0331 17:09:45.395146 1317 slave.cpp:1165] Forwarding total oversubscribed resources
I0331 17:09:45.395349 1318 sched.cpp:874] Scheduler::resourceOffers took 145774ns
I0331 17:09:45.395462 1317 slave.cpp:3664] Received ping from slave-observer(340)@172.17.0.2:47264
I0331 17:09:45.395781 1325 master.cpp:4802] Received update of slave 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 at slave(357)@172.17.0.2:47264 (29ba6f7aca97) with total oversubscribed resources
I0331 17:09:45.396335 1321 hierarchical.cpp:1585] No inverse offers to send out!
I0331 17:09:45.396401 1321 hierarchical.cpp:1164] Performed allocation for slave 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 in 3.112603ms
I0331 17:09:45.396800 1321 hierarchical.cpp:534] Slave 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 (29ba6f7aca97) updated with oversubscribed resources (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000])
I0331 17:09:45.396965 1320 replica.cpp:537] Replica received write request for position 4 from (12043)@172.17.0.2:47264
I0331 17:09:45.396978 1321 hierarchical.cpp:1490] No resources available to allocate!
I0331 17:09:45.397666 1320 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 556870ns
I0331 17:09:45.397699 1320 replica.cpp:712] Persisted action at 4
I0331 17:09:45.397799 1321 hierarchical.cpp:1585] No inverse offers to send out!
I0331 17:09:45.397964 1321 hierarchical.cpp:1164] Performed allocation for slave 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 in 1.122823ms
I0331 17:09:45.399468 1318 master.cpp:3288] Processing ACCEPT call for offers: [ 9b06f6db-72f1-42be-b262-98eb1ddb86e2-O0 ] on slave 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 at slave(357)@172.17.0.2:47264 (29ba6f7aca97) for framework 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000 (default) at scheduler-750dafdb-b99a-49b6-883c-3de70916a485@172.17.0.2:47264
I0331 17:09:45.399530 1318 master.cpp:2891] Authorizing framework principal 'test-principal' to launch task 1 as user 'mesos'
W0331 17:09:45.401288 1318 validation.cpp:416] Executor default for task 1 uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0331 17:09:45.401373 1318 validation.cpp:428] Executor default for task 1 uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0331 17:09:45.401914 1318 master.hpp:177] Adding task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 (29ba6f7aca97)
I0331 17:09:45.403225 1318 master.cpp:3773] Launching task 1 of framework 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000 (default) at scheduler-750dafdb-b99a-49b6-883c-3de70916a485@172.17.0.2:47264 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 at slave(357)@172.17.0.2:47264 (29ba6f7aca97)
I0331 17:09:45.403780 1322 slave.cpp:1497] Got assigned task 1 for framework 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000
I0331 17:09:45.404628 1322 slave.cpp:1616] Launching task 1 for framework 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000
I0331 17:09:45.404757 1318 replica.cpp:691] Replica received learned notice for position 4 from @0.0.0.0:0
I0331 17:09:45.405338 1318 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 557752ns
I0331 17:09:45.405519 1318 leveldb.cpp:399] Deleting ~2 keys from leveldb took 132165ns
I0331 17:09:45.405654 1318 replica.cpp:712] Persisted action at 4
I0331 17:09:45.405788 1318 replica.cpp:697] Replica learned TRUNCATE action at position 4
I0331 17:09:45.406699 1322 paths.cpp:528] Trying to chown '/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/slaves/9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0/frameworks/9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000/executors/default/runs/64ca6446-70ef-4912-98e0-fe9349b5c4f0' to user 'mesos'
I0331 17:09:45.418769 1322 slave.cpp:5575] Launching executor default of framework 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000 with resources in work directory '/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/slaves/9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0/frameworks/9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000/executors/default/runs/64ca6446-70ef-4912-98e0-fe9349b5c4f0'
I0331 17:09:45.422309 1322 exec.cpp:150] Version: 0.29.0
I0331 17:09:45.422802 1320 exec.cpp:200] Executor started at: executor(102)@172.17.0.2:47264 with pid 1299
I0331 17:09:45.423281 1322 slave.cpp:1834] Queuing task '1' for executor 'default' of framework 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000
I0331 17:09:45.423583 1322 slave.cpp:881] Successfully attached file '/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/slaves/9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0/frameworks/9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000/executors/default/runs/64ca6446-70ef-4912-98e0-fe9349b5c4f0'
F0331 17:09:45.425576 1322 owned.hpp:110] Check failed: 'get()' Must be non NULL
*** Check failure stack trace: ***
@ 0x2b84020b04bd google::LogMessage::Fail()
@ 0x2b84020af89e google::LogMessage::SendToLog()
@ 0x2b84020b017d google::LogMessage::Flush()
@ 0x2b84020b35f8 google::LogMessageFatal::~LogMessageFatal()
@ 0xa07364 google::CheckNotNull<>()
@ 0x9f69a2 process::Owned<>::operator->()
@ 0x9f4a6d mesos::internal::tests::TestContainerizer::_wait()
@ 0x9faf66 _ZN7testing8internal12InvokeHelperIN7process6FutureIN5mesos13containerizer11TerminationEEESt5tupleIJRKNS4_11ContainerIDEEEE12InvokeMethodINS4_8internal5tests17TestContainerizerEMSH_FS7_SB_EEES7_PT_T0_RKSC_
@ 0x9faeb4 _ZNK7testing8internal18InvokeMethodActionIN5mesos8internal5tests17TestContainerizerEMS5_FN7process6FutureINS2_13containerizer11TerminationEEERKNS2_11ContainerIDEEE7PerformISA_St5tupleIJSD_EEEET_RKT0_
@ 0x9fae56 _ZN7testing17PolymorphicActionINS_8internal18InvokeMethodActionIN5mesos8internal5tests17TestContainerizerEMS6_FN7process6FutureINS3_13containerizer11TerminationEEERKNS3_11ContainerIDEEEEE15MonomorphicImplIFSB_SE_EE7PerformERKSt5tupleIJSE_EE
@ 0xa30758 _ZNK7testing6ActionIFN7process6FutureIN5mesos13containerizer11TerminationEEERKNS3_11ContainerIDEEE7PerformERKSt5tupleIJS9_EE
@ 0xa305ba testing::internal::ActionResultHolder<>::PerformAction<>()
@ 0xa2f19c testing::internal::FunctionMockerBase<>::UntypedPerformAction()
@ 0x197a679 testing::internal::UntypedFunctionMockerBase::UntypedInvokeWith()
@ 0xa1d09b _ZN7testing8internal18FunctionMockerBaseIFN7process6FutureIN5mesos13containerizer11TerminationEEERKNS4_11ContainerIDEEE10InvokeWithERKSt5tupleIJSA_EE
@ 0xa1d064 testing::internal::FunctionMocker<>::Invoke()
@ 0x9f98c0 mesos::internal::tests::TestContainerizer::wait()
@ 0x2b84012ec6a5 mesos::internal::slave::Slave::executorLaunched()
@ 0x2b84013225ea _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS1_11FrameworkIDERKNS1_10ExecutorIDERKNS1_11ContainerIDERKNS_6FutureIbEES5_S8_SB_SF_EEvRKNS_3PIDIT_EEMSJ_FvT0_T1_T2_T3_ET4_T5_T6_T7_ENKUlPNS_11ProcessBaseEE_clESY_
@ 0x2b8401322112 _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchIN5mesos8internal5slave5SlaveERKNS5_11FrameworkIDERKNS5_10ExecutorIDERKNS5_11ContainerIDERKNS0_6FutureIbEES9_SC_SF_SJ_EEvRKNS0_3PIDIT_EEMSN_FvT0_T1_T2_T3_ET4_T5_T6_T7_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_
@ 0x2b8401fe72e8 std::function<>::operator()()
@ 0x2b8401fd1534 process::ProcessBase::visit()
@ 0x2b84020279ae process::DispatchEvent::visit()
@ 0x86b4f1 process::ProcessBase::serve()
@ 0x2b8401fcf254 process::ProcessManager::resume()
@ 0x2b8401fd7375 process::ProcessManager::init_threads()::$_1::operator()()
@ 0x2b8401fd72b3 _ZNSt5_BindIFZN7process14ProcessManager12init_threadsEvE3$_1St17reference_wrapperIKSt11atomic_boolEEE6__callIvJEJLm0EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
@ 0x2b8401fd7266 _ZNSt5_BindIFZN7process14ProcessManager12init_threadsEvE3$_1St17reference_wrapperIKSt11atomic_boolEEEclIJEvEET0_DpOT_
@ 0x2b8401fd7215 _ZNSt12_Bind_simpleIFSt5_BindIFZN7process14ProcessManager12init_threadsEvE3$_1St17reference_wrapperIKSt11atomic_boolEEEvEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE
@ 0x2b8401fd71e5 std::_Bind_simple<>::operator()()
@ 0x2b8401fd71bc std::thread::_Impl<>::_M_run()
@ 0x2b8403840a60 (unknown)
make[4]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[4]: *** [check-local] Aborted
make[3]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[3]: *** [check-am] Error 2
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.29.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1459442182-6170
Untagged: mesos-1459442182-6170:latest
Deleted: 2d66a00ec0049a3861429ddea6ad000ecee3cde2163dbfd00188809a1a4dccbc
Deleted: 4f22ed5feeb67e6af34b3ca6e647425b7673f368f784fd2002a3d5d691a1984a
Deleted: b738fe0fba889e16578ed5e4c91da22d32cc3dbee2a4bd6e79bd90a89ab84643
Deleted: 586be900ed5b534083e54b8111e242c8071a2b27b2354e86f4e59a076937c9d2
Deleted: 36a17040d363e8c1f1056c63120c4deef43ea9107b8566076780c7b6c2e0814b
Deleted: 9dddb8f7a4e89a5c43a838920a62116550aa98063927ead99257e8d764a7568a
Deleted: 26154f0eaffe623d376982559fb226602cc76479bfbb3b25a099f9affc2d1391
Deleted: 074341c72d94570bd67610567dfd64c082c43c37864089aa1b6893076a881a0e
Deleted: af36b6d6fc35c9bae6adc732dc4216fce2bbd6d7397d39c290c6ff55c15f32a1
Deleted: 6e40d728ba2eb6886bca928e9ac9884fd923aa484c33035b7eff7b8a57c70be8
Deleted: 94f8edab37485281006f5f65690f4571b1ba5012e137b367b1ac0e1f4f733fea
Deleted: d6955d17738051273ef8c18e66b944879130fabbc936f5f7c502890f93cfe2b3
Deleted: 13ba435ee0ad83459ba05018be306c55c0acd4211285521ff828db23bdef8528
Deleted: 89248d43439d536f7b7afa57ae625e859286451f9ef8e49f2d6e97daa6b9d1c6
Deleted: 841671f9606b1431f94603959fa87c3265735cf1546d3317ad08d1eccedd4401
Deleted: 7eb2dc32e199b207e33f2e2b658d720e31fba7bd6609078d94254585e3882140
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos » clang,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1916
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1916/changes>
Build failed in Jenkins: Mesos » clang,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1915
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1915/changes>
Changes:
[vinodkone] Upgrade protobuf to 2.6.1 to support PowerPC LE platform [stout].
[vinodkone] Upgrade protobuf to 2.6.1 to support PowerPC LE platform [libprocess].
[vinodkone] Upgrade protobuf to 2.6.1 to support PowerPC LE platform [mesos].
------------------------------------------
[...truncated 175895 lines...]
I0331 18:44:47.602035 1732 hierarchical.cpp:1585] No inverse offers to send out!
I0331 18:44:47.602238 1732 hierarchical.cpp:1141] Performed allocation for 0 slaves in 385236ns
I0331 18:44:47.602094 1726 master.hpp:1719] Sending heartbeat to 75d77006-5cad-4f0e-ac30-d5d2bda8cbc3-0000
I0331 18:44:47.603169 1729 scheduler.cpp:657] Enqueuing event SUBSCRIBED received from http://172.17.0.2:47086/master/api/v1/scheduler
I0331 18:44:47.604750 1727 scheduler.cpp:231] Sending REQUEST call to http://172.17.0.2:47086/master/api/v1/scheduler
I0331 18:44:47.605238 1727 scheduler.cpp:657] Enqueuing event HEARTBEAT received from http://172.17.0.2:47086/master/api/v1/scheduler
I0331 18:44:47.607158 1730 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0331 18:44:47.607610 1730 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:35264
I0331 18:44:47.607868 1730 master.cpp:2785] Processing REQUEST call for framework 75d77006-5cad-4f0e-ac30-d5d2bda8cbc3-0000 (default)
I0331 18:44:47.608028 1728 hierarchical.cpp:592] Received resource request from framework 75d77006-5cad-4f0e-ac30-d5d2bda8cbc3-0000
I0331 18:44:47.612429 1728 master.cpp:1269] Framework 75d77006-5cad-4f0e-ac30-d5d2bda8cbc3-0000 (default) disconnected
I0331 18:44:47.612460 1728 master.cpp:2642] Disconnecting framework 75d77006-5cad-4f0e-ac30-d5d2bda8cbc3-0000 (default)
I0331 18:44:47.612484 1728 master.cpp:2666] Deactivating framework 75d77006-5cad-4f0e-ac30-d5d2bda8cbc3-0000 (default)
I0331 18:44:47.612570 1728 master.cpp:1293] Giving framework 75d77006-5cad-4f0e-ac30-d5d2bda8cbc3-0000 (default) 0ns to failover
I0331 18:44:47.612735 1719 hierarchical.cpp:378] Deactivated framework 75d77006-5cad-4f0e-ac30-d5d2bda8cbc3-0000
I0331 18:44:47.614013 1728 master.cpp:5360] Framework failover timeout, removing framework 75d77006-5cad-4f0e-ac30-d5d2bda8cbc3-0000 (default)
I0331 18:44:47.614044 1728 master.cpp:6093] Removing framework 75d77006-5cad-4f0e-ac30-d5d2bda8cbc3-0000 (default)
I0331 18:44:47.614356 1728 hierarchical.cpp:329] Removed framework 75d77006-5cad-4f0e-ac30-d5d2bda8cbc3-0000
I0331 18:44:47.622166 1701 master.cpp:1083] Master terminating
[ OK ] ContentType/SchedulerTest.Request/1 (110 ms)
[ RUN ] ContentType/SchedulerTest.SchedulerReconnect/0
I0331 18:44:47.644100 1701 cluster.cpp:139] Creating default 'local' authorizer
I0331 18:44:47.648229 1701 leveldb.cpp:174] Opened db in 3.92707ms
I0331 18:44:47.652968 1701 leveldb.cpp:181] Compacted db in 4.714546ms
I0331 18:44:47.653036 1701 leveldb.cpp:196] Created db iterator in 33494ns
I0331 18:44:47.653059 1701 leveldb.cpp:202] Seeked to beginning of db in 8789ns
I0331 18:44:47.653070 1701 leveldb.cpp:271] Iterated through 0 keys in the db in 6970ns
I0331 18:44:47.653120 1701 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0331 18:44:47.654518 1732 recover.cpp:447] Starting replica recovery
I0331 18:44:47.654793 1732 recover.cpp:473] Replica is in EMPTY status
I0331 18:44:47.657028 1732 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (16403)@172.17.0.2:47086
I0331 18:44:47.657546 1732 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0331 18:44:47.658187 1732 recover.cpp:564] Updating replica status to STARTING
I0331 18:44:47.658936 1727 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 601940ns
I0331 18:44:47.658984 1727 replica.cpp:320] Persisted replica status to STARTING
I0331 18:44:47.659167 1726 recover.cpp:473] Replica is in STARTING status
I0331 18:44:47.660605 1727 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (16404)@172.17.0.2:47086
I0331 18:44:47.660997 1729 recover.cpp:193] Received a recover response from a replica in STARTING status
I0331 18:44:47.661375 1722 master.cpp:376] Master d3311895-3ea7-410d-b959-d7cd25f8194c (ff176ecfc889) started on 172.17.0.2:47086
I0331 18:44:47.661413 1722 master.cpp:378] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/6o2YEF/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.29.0/_inst/share/mesos/webui" --work_dir="/tmp/6o2YEF/master" --zk_session_timeout="10secs"
I0331 18:44:47.661819 1722 master.cpp:429] Master allowing unauthenticated frameworks to register
I0331 18:44:47.661833 1722 master.cpp:432] Master only allowing authenticated slaves to register
I0331 18:44:47.661842 1722 credentials.hpp:37] Loading credentials for authentication from '/tmp/6o2YEF/credentials'
I0331 18:44:47.662050 1733 recover.cpp:564] Updating replica status to VOTING
I0331 18:44:47.662216 1722 master.cpp:474] Using default 'crammd5' authenticator
I0331 18:44:47.662356 1722 master.cpp:545] Using default 'basic' HTTP authenticator
I0331 18:44:47.662566 1722 master.cpp:583] Authorization enabled
I0331 18:44:47.662746 1730 whitelist_watcher.cpp:77] No whitelist given
I0331 18:44:47.662808 1731 hierarchical.cpp:144] Initialized hierarchical allocator process
I0331 18:44:47.662981 1733 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 797605ns
I0331 18:44:47.663009 1733 replica.cpp:320] Persisted replica status to VOTING
I0331 18:44:47.663113 1733 recover.cpp:578] Successfully joined the Paxos group
I0331 18:44:47.663321 1733 recover.cpp:462] Recover process terminated
I0331 18:44:47.665294 1722 master.cpp:1826] The newly elected leader is master@172.17.0.2:47086 with id d3311895-3ea7-410d-b959-d7cd25f8194c
I0331 18:44:47.665405 1722 master.cpp:1839] Elected as the leading master!
I0331 18:44:47.665519 1722 master.cpp:1526] Recovering from registrar
I0331 18:44:47.665838 1722 registrar.cpp:307] Recovering registrar
I0331 18:44:47.666663 1732 log.cpp:659] Attempting to start the writer
I0331 18:44:47.668149 1726 replica.cpp:493] Replica received implicit promise request from (16406)@172.17.0.2:47086 with proposal 1
I0331 18:44:47.668982 1726 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 753851ns
I0331 18:44:47.669010 1726 replica.cpp:342] Persisted promised to 1
I0331 18:44:47.669905 1726 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0331 18:44:47.671332 1726 replica.cpp:388] Replica received explicit promise request from (16407)@172.17.0.2:47086 for position 0 with proposal 2
I0331 18:44:47.671983 1726 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 564742ns
I0331 18:44:47.672011 1726 replica.cpp:712] Persisted action at 0
I0331 18:44:47.673142 1723 replica.cpp:537] Replica received write request for position 0 from (16408)@172.17.0.2:47086
I0331 18:44:47.673200 1723 leveldb.cpp:436] Reading position from leveldb took 40254ns
I0331 18:44:47.673981 1723 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 755707ns
I0331 18:44:47.674008 1723 replica.cpp:712] Persisted action at 0
I0331 18:44:47.674705 1723 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0331 18:44:47.675982 1723 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.265358ms
I0331 18:44:47.676022 1723 replica.cpp:712] Persisted action at 0
I0331 18:44:47.676041 1723 replica.cpp:697] Replica learned NOP action at position 0
I0331 18:44:47.676820 1723 log.cpp:675] Writer started with ending position 0
I0331 18:44:47.678035 1733 leveldb.cpp:436] Reading position from leveldb took 45428ns
I0331 18:44:47.679097 1727 registrar.cpp:340] Successfully fetched the registry (0B) in 13.021952ms
I0331 18:44:47.679306 1727 registrar.cpp:439] Applied 1 operations in 52691ns; attempting to update the 'registry'
I0331 18:44:47.680650 1727 log.cpp:683] Attempting to append 170 bytes to the log
I0331 18:44:47.681025 1731 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0331 18:44:47.682132 1729 replica.cpp:537] Replica received write request for position 1 from (16409)@172.17.0.2:47086
I0331 18:44:47.682993 1729 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 760530ns
I0331 18:44:47.683027 1729 replica.cpp:712] Persisted action at 1
I0331 18:44:47.684136 1729 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0331 18:44:47.684645 1729 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 495022ns
I0331 18:44:47.684674 1729 replica.cpp:712] Persisted action at 1
I0331 18:44:47.684696 1729 replica.cpp:697] Replica learned APPEND action at position 1
I0331 18:44:47.686131 1723 log.cpp:702] Attempting to truncate the log to 1
I0331 18:44:47.686142 1730 registrar.cpp:484] Successfully updated the 'registry' in 6.763008ms
I0331 18:44:47.686283 1730 registrar.cpp:370] Successfully recovered registrar
I0331 18:44:47.686420 1723 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0331 18:44:47.686720 1730 master.cpp:1634] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0331 18:44:47.686837 1723 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0331 18:44:47.687643 1723 replica.cpp:537] Replica received write request for position 2 from (16410)@172.17.0.2:47086
I0331 18:44:47.688989 1723 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.242833ms
I0331 18:44:47.689029 1723 replica.cpp:712] Persisted action at 2
I0331 18:44:47.690060 1721 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0331 18:44:47.690387 1721 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 312122ns
I0331 18:44:47.690443 1721 leveldb.cpp:399] Deleting ~1 keys from leveldb took 40292ns
I0331 18:44:47.690464 1721 replica.cpp:712] Persisted action at 2
I0331 18:44:47.690487 1721 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0331 18:44:47.698920 1701 scheduler.cpp:172] Version: 0.29.0
I0331 18:44:47.699501 1724 scheduler.cpp:466] New master detected at master@172.17.0.2:47086
I0331 18:44:47.702755 1727 scheduler.cpp:355] Connected with the master at http://172.17.0.2:47086/master/api/v1/scheduler
I0331 18:44:47.705643 1728 scheduler.cpp:437] Re-detecting master
I0331 18:44:47.705833 1728 scheduler.cpp:388] Ignoring disconnection attempt from stale connection
I0331 18:44:47.705869 1728 scheduler.cpp:388] Ignoring disconnection attempt from stale connection
I0331 18:44:47.706756 1728 scheduler.cpp:466] New master detected at master@172.17.0.2:47086
I0331 18:44:47.708014 1729 scheduler.cpp:355] Connected with the master at http://172.17.0.2:47086/master/api/v1/scheduler
I0331 18:44:47.709656 1729 scheduler.cpp:441] Lost leading master
I0331 18:44:47.709838 1729 scheduler.cpp:388] Ignoring disconnection attempt from stale connection
I0331 18:44:47.709873 1729 scheduler.cpp:388] Ignoring disconnection attempt from stale connection
I0331 18:44:47.711102 1728 scheduler.cpp:275] Ignoring reconnect request from scheduler since we are disconnected
I0331 18:44:47.738315 1701 master.cpp:1083] Master terminating
[ OK ] ContentType/SchedulerTest.SchedulerReconnect/0 (106 ms)
[ RUN ] ContentType/SchedulerTest.SchedulerReconnect/1
I0331 18:44:47.750710 1701 cluster.cpp:139] Creating default 'local' authorizer
I0331 18:44:47.756223 1701 leveldb.cpp:174] Opened db in 5.232733ms
I0331 18:44:47.759970 1701 leveldb.cpp:181] Compacted db in 3.727542ms
I0331 18:44:47.760035 1701 leveldb.cpp:196] Created db iterator in 33053ns
I0331 18:44:47.760053 1701 leveldb.cpp:202] Seeked to beginning of db in 9441ns
I0331 18:44:47.760066 1701 leveldb.cpp:271] Iterated through 0 keys in the db in 7113ns
I0331 18:44:47.760110 1701 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0331 18:44:47.761327 1725 recover.cpp:447] Starting replica recovery
I0331 18:44:47.761855 1725 recover.cpp:473] Replica is in EMPTY status
I0331 18:44:47.763116 1720 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (16414)@172.17.0.2:47086
I0331 18:44:47.763509 1728 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0331 18:44:47.763942 1725 recover.cpp:564] Updating replica status to STARTING
I0331 18:44:47.765010 1726 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 986141ns
I0331 18:44:47.765058 1726 replica.cpp:320] Persisted replica status to STARTING
I0331 18:44:47.765805 1722 master.cpp:376] Master 2197614c-2ca3-427d-ad84-15416cd07f15 (ff176ecfc889) started on 172.17.0.2:47086
I0331 18:44:47.766160 1726 recover.cpp:473] Replica is in STARTING status
I0331 18:44:47.765902 1722 master.cpp:378] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/4FsFXq/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.29.0/_inst/share/mesos/webui" --work_dir="/tmp/4FsFXq/master" --zk_session_timeout="10secs"
I0331 18:44:47.766494 1722 master.cpp:429] Master allowing unauthenticated frameworks to register
I0331 18:44:47.766562 1722 master.cpp:432] Master only allowing authenticated slaves to register
I0331 18:44:47.766646 1722 credentials.hpp:37] Loading credentials for authentication from '/tmp/4FsFXq/credentials'
I0331 18:44:47.767060 1722 master.cpp:474] Using default 'crammd5' authenticator
I0331 18:44:47.767261 1722 master.cpp:545] Using default 'basic' HTTP authenticator
I0331 18:44:47.767437 1722 master.cpp:583] Authorization enabled
I0331 18:44:47.768009 1725 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (16415)@172.17.0.2:47086
I0331 18:44:47.768344 1725 hierarchical.cpp:144] Initialized hierarchical allocator process
I0331 18:44:47.768445 1725 recover.cpp:193] Received a recover response from a replica in STARTING status
I0331 18:44:47.768843 1725 whitelist_watcher.cpp:77] No whitelist given
I0331 18:44:47.769739 1719 recover.cpp:564] Updating replica status to VOTING
I0331 18:44:47.770385 1719 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 512906ns
I0331 18:44:47.770419 1719 replica.cpp:320] Persisted replica status to VOTING
I0331 18:44:47.770557 1719 recover.cpp:578] Successfully joined the Paxos group
I0331 18:44:47.770736 1719 recover.cpp:462] Recover process terminated
I0331 18:44:47.771790 1722 master.cpp:1826] The newly elected leader is master@172.17.0.2:47086 with id 2197614c-2ca3-427d-ad84-15416cd07f15
I0331 18:44:47.771826 1722 master.cpp:1839] Elected as the leading master!
I0331 18:44:47.771839 1722 master.cpp:1526] Recovering from registrar
I0331 18:44:47.771989 1719 registrar.cpp:307] Recovering registrar
I0331 18:44:47.773633 1722 log.cpp:659] Attempting to start the writer
I0331 18:44:47.775097 1720 replica.cpp:493] Replica received implicit promise request from (16417)@172.17.0.2:47086 with proposal 1
I0331 18:44:47.775553 1720 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 436111ns
I0331 18:44:47.775580 1720 replica.cpp:342] Persisted promised to 1
I0331 18:44:47.776110 1720 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0331 18:44:47.777561 1726 replica.cpp:388] Replica received explicit promise request from (16418)@172.17.0.2:47086 for position 0 with proposal 2
I0331 18:44:47.777993 1726 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 328148ns
I0331 18:44:47.778081 1726 replica.cpp:712] Persisted action at 0
I0331 18:44:47.779543 1726 replica.cpp:537] Replica received write request for position 0 from (16419)@172.17.0.2:47086
I0331 18:44:47.780067 1726 leveldb.cpp:436] Reading position from leveldb took 53566ns
I0331 18:44:47.780525 1726 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 335868ns
I0331 18:44:47.780552 1726 replica.cpp:712] Persisted action at 0
I0331 18:44:47.782250 1725 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0331 18:44:47.783982 1725 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.711909ms
I0331 18:44:47.784026 1725 replica.cpp:712] Persisted action at 0
I0331 18:44:47.784050 1725 replica.cpp:697] Replica learned NOP action at position 0
I0331 18:44:47.784832 1729 log.cpp:675] Writer started with ending position 0
I0331 18:44:47.786310 1726 leveldb.cpp:436] Reading position from leveldb took 69156ns
I0331 18:44:47.787462 1722 registrar.cpp:340] Successfully fetched the registry (0B) in 15.426048ms
I0331 18:44:47.787598 1722 registrar.cpp:439] Applied 1 operations in 71835ns; attempting to update the 'registry'
I0331 18:44:47.788450 1728 log.cpp:683] Attempting to append 170 bytes to the log
I0331 18:44:47.788633 1728 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0331 18:44:47.789638 1728 replica.cpp:537] Replica received write request for position 1 from (16420)@172.17.0.2:47086
I0331 18:44:47.790172 1728 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 502371ns
I0331 18:44:47.790279 1728 replica.cpp:712] Persisted action at 1
I0331 18:44:47.791118 1726 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0331 18:44:47.791474 1726 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 337360ns
I0331 18:44:47.791504 1726 replica.cpp:712] Persisted action at 1
I0331 18:44:47.791528 1726 replica.cpp:697] Replica learned APPEND action at position 1
I0331 18:44:47.792812 1724 registrar.cpp:484] Successfully updated the 'registry' in 5.108992ms
I0331 18:44:47.793074 1724 registrar.cpp:370] Successfully recovered registrar
I0331 18:44:47.793511 1724 master.cpp:1634] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0331 18:44:47.793789 1724 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0331 18:44:47.793988 1719 log.cpp:702] Attempting to truncate the log to 1
I0331 18:44:47.794211 1719 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0331 18:44:47.795158 1730 replica.cpp:537] Replica received write request for position 2 from (16421)@172.17.0.2:47086
I0331 18:44:47.795653 1730 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 467812ns
I0331 18:44:47.795682 1730 replica.cpp:712] Persisted action at 2
I0331 18:44:47.796655 1730 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0331 18:44:47.797101 1730 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 428505ns
I0331 18:44:47.797169 1730 leveldb.cpp:399] Deleting ~1 keys from leveldb took 52203ns
I0331 18:44:47.797194 1730 replica.cpp:712] Persisted action at 2
I0331 18:44:47.797217 1730 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0331 18:44:47.804872 1701 scheduler.cpp:172] Version: 0.29.0
I0331 18:44:47.805361 1725 scheduler.cpp:466] New master detected at master@172.17.0.2:47086
I0331 18:44:47.809020 1720 scheduler.cpp:355] Connected with the master at http://172.17.0.2:47086/master/api/v1/scheduler
I0331 18:44:47.810856 1720 scheduler.cpp:437] Re-detecting master
I0331 18:44:47.811051 1720 scheduler.cpp:388] Ignoring disconnection attempt from stale connection
I0331 18:44:47.811092 1720 scheduler.cpp:388] Ignoring disconnection attempt from stale connection
I0331 18:44:47.811556 1720 scheduler.cpp:466] New master detected at master@172.17.0.2:47086
I0331 18:44:47.813472 1720 scheduler.cpp:355] Connected with the master at http://172.17.0.2:47086/master/api/v1/scheduler
I0331 18:44:47.815033 1732 scheduler.cpp:441] Lost leading master
I0331 18:44:47.815234 1732 scheduler.cpp:388] Ignoring disconnection attempt from stale connection
I0331 18:44:47.815989 1732 scheduler.cpp:388] Ignoring disconnection attempt from stale connection
I0331 18:44:47.816032 1732 scheduler.cpp:275] Ignoring reconnect request from scheduler since we are disconnected
I0331 18:44:47.836448 1701 master.cpp:1083] Master terminating
[ OK ] ContentType/SchedulerTest.SchedulerReconnect/1 (97 ms)
[----------] 28 tests from ContentType/SchedulerTest (7120 ms total)
[----------] Global test environment tear-down
E0331 18:44:48.035821 1701 environment.cpp:778] Failed to remove '/tmp/LogZooKeeperTest_WriteRead_a0pGjR': No such file or directory
E0331 18:44:48.036042 1701 environment.cpp:778] Failed to remove '/tmp/LogZooKeeperTest_LostZooKeeper_TdlYTo': No such file or directory
[==========] 1054 tests from 132 test cases ran. (405890 ms total)
[ PASSED ] 1053 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] MasterSlaveReconciliationTest.ReconcileRace
1 FAILED TEST
YOU HAVE 6 DISABLED TESTS
make[4]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[4]: *** [check-local] Error 1
make[3]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[3]: *** [check-am] Error 2
make[2]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[2]: *** [check] Error 2
make[1]: Leaving directory `/mesos/mesos-0.29.0/_build'
make[1]: *** [check-recursive] Error 1
make: *** [distcheck] Error 1
+ docker rmi mesos-1459447880-16138
Untagged: mesos-1459447880-16138:latest
Deleted: 39c198ae37fec27f0947e3f81249f979a398788e0120463e7c2095f9e7adef80
Deleted: 8c616838d10a57a124066fd7e875e95587a000fcd3d20a0dadcd803dbd7d74ba
Deleted: fa936ef072dce0aabdbb753a8200989c83e11ccbb99f921553717167eaf00a72
Deleted: ac1834293644a16fa87e5089351382ec5b8cf82b5193b972a826e0eee26dd2f6
Deleted: 932cc1c7eb7c5449d99b7d2d2f42f5340482cf940a08b78c1dc93014db0a7c4e
Deleted: e1bd7b2946cfc0792891815c1ac8327c006b2439a64f1a7604ce2b1a1ad3ef66
Deleted: 9f5aa9418c9e37d6cdd2d111ff45ee9e7228cfd690f65b357d127f62fbd06def
Deleted: 7ab0cd730325baa3b848b230024665ab3ed3c4d4a2253390eec5936a9415d370
Deleted: 70e265835f21c0cc4d4a08a19ceeb5081e58f69329e5fbd16d2c8fa871c3fdbc
Deleted: bbef7ac560b82df84ce638f06909222e349e65c0a76d43c58b4dd3709ad72dcd
Deleted: 57cfe18ecd3b7c4a5bcc9183cbedae0e17784ef3f590ab641e8056e2b71ffb0f
Deleted: b0abf7e0d9e048258df566642395ccf394fdffda94734848816a7031e5cb9f9e
Deleted: 16401d05d4c1d9ead3e860c7f9a0fd345e1b7d7bf36ebd67c781ef42324cf2e9
Deleted: 966baa09b46f564c82ff22dbddc7bee23e13aa61397c9470f18399922aa44fc9
Deleted: 73fe103530653af0f0db70d3ec967e985783a0dba9fdf9d78b09f4b81cf162c2
Deleted: 303287cc292f82827371023a06ec7b406a3c53c050d7659e90de989766e98cf9
Build step 'Execute shell' marked build as failure
Re: Build failed in Jenkins: Mesos » clang,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1914
Posted by Greg Mann <gr...@mesosphere.io>.
This looks like a new failure, so I created a ticket:
https://issues.apache.org/jira/browse/MESOS-5074
cc Bernd: perhaps this can go on the list of flaky tests to investigate?
I'll spend more time triaging it during this sprint when I have a few
minutes.
On Thu, Mar 31, 2016 at 10:09 AM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> See <
> https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1914/changes
> >
>
> Changes:
>
> [yujie.jay] Add JSON::Object::at() helper API.
>
> [yujie.jay] Fix the local Docker puller for private registries.
>
> ------------------------------------------
> [...truncated 136807 lines...]
> I0331 17:09:45.320572 1317 master.cpp:427] Master only allowing
> authenticated frameworks to register
> I0331 17:09:45.320585 1317 master.cpp:432] Master only allowing
> authenticated slaves to register
> I0331 17:09:45.320595 1317 credentials.hpp:37] Loading credentials for
> authentication from '/tmp/HoiV0t/credentials'
> I0331 17:09:45.321111 1317 master.cpp:474] Using default 'crammd5'
> authenticator
> I0331 17:09:45.321318 1317 master.cpp:545] Using default 'basic' HTTP
> authenticator
> I0331 17:09:45.321517 1317 master.cpp:583] Authorization enabled
> I0331 17:09:45.321770 1325 hierarchical.cpp:144] Initialized hierarchical
> allocator process
> I0331 17:09:45.321846 1325 whitelist_watcher.cpp:77] No whitelist given
> I0331 17:09:45.325551 1317 master.cpp:1826] The newly elected leader is
> master@172.17.0.2:47264 with id 9b06f6db-72f1-42be-b262-98eb1ddb86e2
> I0331 17:09:45.325592 1317 master.cpp:1839] Elected as the leading master!
> I0331 17:09:45.325604 1317 master.cpp:1526] Recovering from registrar
> I0331 17:09:45.325875 1331 registrar.cpp:307] Recovering registrar
> I0331 17:09:45.326637 1331 log.cpp:659] Attempting to start the writer
> I0331 17:09:45.328130 1324 replica.cpp:493] Replica received implicit
> promise request from (12032)@172.17.0.2:47264 with proposal 1
> I0331 17:09:45.328989 1324 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 832457ns
> I0331 17:09:45.329020 1324 replica.cpp:342] Persisted promised to 1
> I0331 17:09:45.329779 1329 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0331 17:09:45.332118 1329 replica.cpp:388] Replica received explicit
> promise request from (12033)@172.17.0.2:47264 for position 0 with
> proposal 2
> I0331 17:09:45.332484 1329 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 333132ns
> I0331 17:09:45.332510 1329 replica.cpp:712] Persisted action at 0
> I0331 17:09:45.333724 1318 replica.cpp:537] Replica received write
> request for position 0 from (12034)@172.17.0.2:47264
> I0331 17:09:45.333792 1318 leveldb.cpp:436] Reading position from leveldb
> took 45226ns
> I0331 17:09:45.334182 1318 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 358530ns
> I0331 17:09:45.334213 1318 replica.cpp:712] Persisted action at 0
> I0331 17:09:45.335052 1318 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0331 17:09:45.335985 1318 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 920454ns
> I0331 17:09:45.336016 1318 replica.cpp:712] Persisted action at 0
> I0331 17:09:45.336040 1318 replica.cpp:697] Replica learned NOP action at
> position 0
> I0331 17:09:45.336995 1327 log.cpp:675] Writer started with ending
> position 0
> I0331 17:09:45.338444 1319 leveldb.cpp:436] Reading position from leveldb
> took 66714ns
> I0331 17:09:45.339751 1323 registrar.cpp:340] Successfully fetched the
> registry (0B) in 13.831936ms
> I0331 17:09:45.339889 1323 registrar.cpp:439] Applied 1 operations in
> 45178ns; attempting to update the 'registry'
> I0331 17:09:45.340608 1325 log.cpp:683] Attempting to append 170 bytes to
> the log
> I0331 17:09:45.340701 1319 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0331 17:09:45.341581 1320 replica.cpp:537] Replica received write
> request for position 1 from (12035)@172.17.0.2:47264
> I0331 17:09:45.342003 1320 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 395996ns
> I0331 17:09:45.342036 1320 replica.cpp:712] Persisted action at 1
> I0331 17:09:45.343068 1320 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I0331 17:09:45.343983 1320 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 781249ns
> I0331 17:09:45.344081 1320 replica.cpp:712] Persisted action at 1
> I0331 17:09:45.344189 1320 replica.cpp:697] Replica learned APPEND action
> at position 1
> I0331 17:09:45.345816 1320 registrar.cpp:484] Successfully updated the
> 'registry' in 5.862144ms
> I0331 17:09:45.346009 1319 log.cpp:702] Attempting to truncate the log to
> 1
> I0331 17:09:45.346058 1320 registrar.cpp:370] Successfully recovered
> registrar
> I0331 17:09:45.346191 1319 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I0331 17:09:45.347513 1317 replica.cpp:537] Replica received write
> request for position 2 from (12036)@172.17.0.2:47264
> I0331 17:09:45.347892 1320 master.cpp:1634] Recovered 0 slaves from the
> Registry (131B) ; allowing 10mins for slaves to re-register
> I0331 17:09:45.347992 1317 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 434355ns
> I0331 17:09:45.348021 1317 replica.cpp:712] Persisted action at 2
> I0331 17:09:45.348026 1323 hierarchical.cpp:171] Skipping recovery of
> hierarchical allocator: nothing to recover
> I0331 17:09:45.348672 1326 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I0331 17:09:45.349053 1326 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 362152ns
> I0331 17:09:45.349113 1326 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 44449ns
> I0331 17:09:45.349138 1326 replica.cpp:712] Persisted action at 2
> I0331 17:09:45.349161 1326 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I0331 17:09:45.364617 1331 slave.cpp:200] Slave started on 357)@
> 172.17.0.2:47264
> I0331 17:09:45.364680 1331 slave.cpp:201] Flags at startup:
> --appc_simple_discovery_uri_prefix="http://"
> --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http="true"
> --authenticatee="crammd5" --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/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/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"
> --enforce_container_disk_quota="false"
> --executor_registration_timeout="1mins"
> --executor_shutdown_grace_period="5secs"
> --fetcher_cache_dir="/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/fetch"
> --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks"
> --gc_disk_headroom="0.1" --hadoop_home="" --help="false"
> --hostname_lookup="true" --http_authenticators="basic"
> --http_credentials="/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/http_credentials"
> --image_provisioner_backend="copy" --initialize_driver_logging="true"
> --isolation="posix/cpu,posix/mem"
> --launcher_dir="/mesos/mesos-0.29.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;mem:1024;disk:1024;ports:[31000-32000]"
> --revocable_cpu_low_priority="true"
> --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/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi"
> I0331 17:09:45.365267 1331 credentials.hpp:86] Loading credential for
> authentication from
> '/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/credential'
> I0331 17:09:45.365537 1331 slave.cpp:338] Slave using credential for:
> test-principal
> I0331 17:09:45.365581 1331 credentials.hpp:37] Loading credentials for
> authentication from
> '/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/http_credentials'
> I0331 17:09:45.365871 1331 slave.cpp:390] Using default 'basic' HTTP
> authenticator
> I0331 17:09:45.366381 1331 resources.cpp:572] Parsing resources as JSON
> failed: cpus:2;mem:1024;disk:1024;ports:[31000-32000]
> Trying semicolon-delimited string format instead
> I0331 17:09:45.366503 1299 sched.cpp:222] Version: 0.29.0
> I0331 17:09:45.366998 1331 slave.cpp:589] Slave resources: cpus(*):2;
> mem(*):1024; disk(*):1024; ports(*):[31000-32000]
> I0331 17:09:45.367086 1331 slave.cpp:597] Slave attributes: [ ]
> I0331 17:09:45.367101 1331 slave.cpp:602] Slave hostname: 29ba6f7aca97
> I0331 17:09:45.367518 1321 sched.cpp:326] New master detected at
> master@172.17.0.2:47264
> I0331 17:09:45.367595 1321 sched.cpp:382] Authenticating with master
> master@172.17.0.2:47264
> I0331 17:09:45.367617 1321 sched.cpp:389] Using default CRAM-MD5
> authenticatee
> I0331 17:09:45.367931 1321 authenticatee.cpp:121] Creating new client
> SASL connection
> I0331 17:09:45.368347 1321 master.cpp:5679] Authenticating
> scheduler-750dafdb-b99a-49b6-883c-3de70916a485@172.17.0.2:47264
> I0331 17:09:45.368533 1321 authenticator.cpp:413] Starting authentication
> session for crammd5_authenticatee(753)@172.17.0.2:47264
> I0331 17:09:45.368839 1321 authenticator.cpp:98] Creating new server SASL
> connection
> I0331 17:09:45.369026 1317 state.cpp:57] Recovering state from
> '/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/meta'
> I0331 17:09:45.369580 1317 authenticatee.cpp:212] Received SASL
> authentication mechanisms: CRAM-MD5
> I0331 17:09:45.369616 1317 authenticatee.cpp:238] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> I0331 17:09:45.369716 1321 authenticator.cpp:203] Received SASL
> authentication start
> I0331 17:09:45.369781 1321 authenticator.cpp:325] Authentication requires
> more steps
> I0331 17:09:45.369848 1320 status_update_manager.cpp:200] Recovering
> status update manager
> I0331 17:09:45.369868 1321 authenticatee.cpp:258] Received SASL
> authentication step
> I0331 17:09:45.370017 1320 authenticator.cpp:231] Received SASL
> authentication step
> I0331 17:09:45.370057 1320 auxprop.cpp:107] Request to lookup properties
> for user: 'test-principal' realm: '29ba6f7aca97' server FQDN:
> '29ba6f7aca97' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> I0331 17:09:45.370074 1320 auxprop.cpp:179] Looking up auxiliary property
> '*userPassword'
> I0331 17:09:45.370127 1320 auxprop.cpp:179] Looking up auxiliary property
> '*cmusaslsecretCRAM-MD5'
> I0331 17:09:45.370151 1321 slave.cpp:4773] Finished recovery
> I0331 17:09:45.370156 1320 auxprop.cpp:107] Request to lookup properties
> for user: 'test-principal' realm: '29ba6f7aca97' server FQDN:
> '29ba6f7aca97' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> I0331 17:09:45.370182 1320 auxprop.cpp:129] Skipping auxiliary property
> '*userPassword' since SASL_AUXPROP_AUTHZID == true
> I0331 17:09:45.370193 1320 auxprop.cpp:129] Skipping auxiliary property
> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> I0331 17:09:45.370211 1320 authenticator.cpp:317] Authentication success
> I0331 17:09:45.370369 1320 authenticatee.cpp:298] Authentication success
> I0331 17:09:45.370455 1320 master.cpp:5709] Successfully authenticated
> principal 'test-principal' at
> scheduler-750dafdb-b99a-49b6-883c-3de70916a485@172.17.0.2:47264
> I0331 17:09:45.370546 1320 authenticator.cpp:431] Authentication session
> cleanup for crammd5_authenticatee(753)@172.17.0.2:47264
> I0331 17:09:45.370789 1321 slave.cpp:4945] Querying resource estimator
> for oversubscribable resources
> I0331 17:09:45.371309 1321 status_update_manager.cpp:174] Pausing sending
> status updates
> I0331 17:09:45.371325 1326 slave.cpp:928] New master detected at
> master@172.17.0.2:47264
> I0331 17:09:45.371408 1326 slave.cpp:991] Authenticating with master
> master@172.17.0.2:47264
> I0331 17:09:45.371428 1326 slave.cpp:996] Using default CRAM-MD5
> authenticatee
> I0331 17:09:45.371578 1326 slave.cpp:964] Detecting new master
> I0331 17:09:45.371677 1321 authenticatee.cpp:121] Creating new client
> SASL connection
> I0331 17:09:45.372030 1317 master.cpp:5679] Authenticating slave(357)@
> 172.17.0.2:47264
> I0331 17:09:45.372042 1330 slave.cpp:4959] Received oversubscribable
> resources from the resource estimator
> I0331 17:09:45.372134 1330 authenticator.cpp:413] Starting authentication
> session for crammd5_authenticatee(754)@172.17.0.2:47264
> I0331 17:09:45.372319 1322 authenticator.cpp:98] Creating new server SASL
> connection
> I0331 17:09:45.372548 1321 authenticatee.cpp:212] Received SASL
> authentication mechanisms: CRAM-MD5
> I0331 17:09:45.372582 1321 authenticatee.cpp:238] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> I0331 17:09:45.372684 1322 authenticator.cpp:203] Received SASL
> authentication start
> I0331 17:09:45.372746 1322 authenticator.cpp:325] Authentication requires
> more steps
> I0331 17:09:45.372835 1322 authenticatee.cpp:258] Received SASL
> authentication step
> I0331 17:09:45.372928 1322 authenticator.cpp:231] Received SASL
> authentication step
> I0331 17:09:45.373037 1322 auxprop.cpp:107] Request to lookup properties
> for user: 'test-principal' realm: '29ba6f7aca97' server FQDN:
> '29ba6f7aca97' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> I0331 17:09:45.373059 1322 auxprop.cpp:179] Looking up auxiliary property
> '*userPassword'
> I0331 17:09:45.373107 1322 auxprop.cpp:179] Looking up auxiliary property
> '*cmusaslsecretCRAM-MD5'
> I0331 17:09:45.373134 1322 auxprop.cpp:107] Request to lookup properties
> for user: 'test-principal' realm: '29ba6f7aca97' server FQDN:
> '29ba6f7aca97' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> I0331 17:09:45.373147 1322 auxprop.cpp:129] Skipping auxiliary property
> '*userPassword' since SASL_AUXPROP_AUTHZID == true
> I0331 17:09:45.373155 1322 auxprop.cpp:129] Skipping auxiliary property
> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> I0331 17:09:45.373173 1322 authenticator.cpp:317] Authentication success
> I0331 17:09:45.373255 1320 authenticatee.cpp:298] Authentication success
> I0331 17:09:45.373327 1322 master.cpp:5709] Successfully authenticated
> principal 'test-principal' at slave(357)@172.17.0.2:47264
> I0331 17:09:45.373345 1320 authenticator.cpp:431] Authentication session
> cleanup for crammd5_authenticatee(754)@172.17.0.2:47264
> I0331 17:09:45.373495 1329 sched.cpp:472] Successfully authenticated with
> master master@172.17.0.2:47264
> I0331 17:09:45.373517 1329 sched.cpp:777] Sending SUBSCRIBE call to
> master@172.17.0.2:47264
> I0331 17:09:45.373615 1329 sched.cpp:810] Will retry registration in
> 1.487722594secs if necessary
> I0331 17:09:45.373731 1320 master.cpp:2346] Received SUBSCRIBE call for
> framework 'default' at
> scheduler-750dafdb-b99a-49b6-883c-3de70916a485@172.17.0.2:47264
> I0331 17:09:45.373754 1320 master.cpp:1865] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I0331 17:09:45.374069 1320 master.cpp:2417] Subscribing framework default
> with checkpointing disabled and capabilities [ ]
> I0331 17:09:45.374737 1320 hierarchical.cpp:266] Added framework
> 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000
> I0331 17:09:45.374830 1320 hierarchical.cpp:1490] No resources available
> to allocate!
> I0331 17:09:45.374868 1320 hierarchical.cpp:1585] No inverse offers to
> send out!
> I0331 17:09:45.374927 1320 hierarchical.cpp:1141] Performed allocation
> for 0 slaves in 176144ns
> I0331 17:09:45.375205 1317 sched.cpp:704] Framework registered with
> 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000
> I0331 17:09:45.375283 1317 sched.cpp:718] Scheduler::registered took
> 32966ns
> I0331 17:09:45.375393 1322 slave.cpp:1061] Successfully authenticated
> with master master@172.17.0.2:47264
> I0331 17:09:45.375533 1322 slave.cpp:1457] Will retry registration in
> 15.182386ms if necessary
> I0331 17:09:45.375825 1322 master.cpp:4390] Registering slave at
> slave(357)@172.17.0.2:47264 (29ba6f7aca97) with id
> 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0
> I0331 17:09:45.376332 1320 registrar.cpp:439] Applied 1 operations in
> 81112ns; attempting to update the 'registry'
> I0331 17:09:45.377719 1322 log.cpp:683] Attempting to append 339 bytes to
> the log
> I0331 17:09:45.377935 1322 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 3
> I0331 17:09:45.379067 1330 replica.cpp:537] Replica received write
> request for position 3 from (12042)@172.17.0.2:47264
> I0331 17:09:45.379992 1330 leveldb.cpp:341] Persisting action (358 bytes)
> to leveldb took 884750ns
> I0331 17:09:45.380023 1330 replica.cpp:712] Persisted action at 3
> I0331 17:09:45.381299 1330 replica.cpp:691] Replica received learned
> notice for position 3 from @0.0.0.0:0
> I0331 17:09:45.381980 1330 leveldb.cpp:341] Persisting action (360 bytes)
> to leveldb took 604022ns
> I0331 17:09:45.382009 1330 replica.cpp:712] Persisted action at 3
> I0331 17:09:45.382030 1330 replica.cpp:697] Replica learned APPEND action
> at position 3
> I0331 17:09:45.391926 1321 registrar.cpp:484] Successfully updated the
> 'registry' in 15520us
> I0331 17:09:45.392407 1322 log.cpp:702] Attempting to truncate the log to
> 3
> I0331 17:09:45.392673 1322 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 4
> I0331 17:09:45.392875 1321 master.cpp:4458] Registered slave
> 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 at slave(357)@172.17.0.2:47264
> (29ba6f7aca97) with cpus(*):2; mem(*):1024; disk(*):1024;
> ports(*):[31000-32000]
> I0331 17:09:45.393252 1321 hierarchical.cpp:476] Added slave
> 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 (29ba6f7aca97) with cpus(*):2;
> mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: )
> I0331 17:09:45.393406 1317 slave.cpp:1105] Registered with master
> master@172.17.0.2:47264; given slave ID
> 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0
> I0331 17:09:45.393548 1317 fetcher.cpp:81] Clearing fetcher cache
> I0331 17:09:45.394038 1319 status_update_manager.cpp:181] Resuming
> sending status updates
> I0331 17:09:45.394340 1317 slave.cpp:1128] Checkpointing SlaveInfo to
> '/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/meta/slaves/9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0/
> slave.info'
> I0331 17:09:45.394834 1325 master.cpp:5508] Sending 1 offers to framework
> 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000 (default) at
> scheduler-750dafdb-b99a-49b6-883c-3de70916a485@172.17.0.2:47264
> I0331 17:09:45.395146 1317 slave.cpp:1165] Forwarding total
> oversubscribed resources
> I0331 17:09:45.395349 1318 sched.cpp:874] Scheduler::resourceOffers took
> 145774ns
> I0331 17:09:45.395462 1317 slave.cpp:3664] Received ping from
> slave-observer(340)@172.17.0.2:47264
> I0331 17:09:45.395781 1325 master.cpp:4802] Received update of slave
> 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 at slave(357)@172.17.0.2:47264
> (29ba6f7aca97) with total oversubscribed resources
> I0331 17:09:45.396335 1321 hierarchical.cpp:1585] No inverse offers to
> send out!
> I0331 17:09:45.396401 1321 hierarchical.cpp:1164] Performed allocation
> for slave 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 in 3.112603ms
> I0331 17:09:45.396800 1321 hierarchical.cpp:534] Slave
> 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 (29ba6f7aca97) updated with
> oversubscribed resources (total: cpus(*):2; mem(*):1024; disk(*):1024;
> ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024;
> ports(*):[31000-32000])
> I0331 17:09:45.396965 1320 replica.cpp:537] Replica received write
> request for position 4 from (12043)@172.17.0.2:47264
> I0331 17:09:45.396978 1321 hierarchical.cpp:1490] No resources available
> to allocate!
> I0331 17:09:45.397666 1320 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 556870ns
> I0331 17:09:45.397699 1320 replica.cpp:712] Persisted action at 4
> I0331 17:09:45.397799 1321 hierarchical.cpp:1585] No inverse offers to
> send out!
> I0331 17:09:45.397964 1321 hierarchical.cpp:1164] Performed allocation
> for slave 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 in 1.122823ms
> I0331 17:09:45.399468 1318 master.cpp:3288] Processing ACCEPT call for
> offers: [ 9b06f6db-72f1-42be-b262-98eb1ddb86e2-O0 ] on slave
> 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 at slave(357)@172.17.0.2:47264
> (29ba6f7aca97) for framework 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000
> (default) at
> scheduler-750dafdb-b99a-49b6-883c-3de70916a485@172.17.0.2:47264
> I0331 17:09:45.399530 1318 master.cpp:2891] Authorizing framework
> principal 'test-principal' to launch task 1 as user 'mesos'
> W0331 17:09:45.401288 1318 validation.cpp:416] Executor default for task
> 1 uses less CPUs (None) than the minimum required (0.01). Please update
> your executor, as this will be mandatory in future releases.
> W0331 17:09:45.401373 1318 validation.cpp:428] Executor default for task
> 1 uses less memory (None) than the minimum required (32MB). Please update
> your executor, as this will be mandatory in future releases.
> I0331 17:09:45.401914 1318 master.hpp:177] Adding task 1 with resources
> cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
> 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 (29ba6f7aca97)
> I0331 17:09:45.403225 1318 master.cpp:3773] Launching task 1 of framework
> 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000 (default) at
> scheduler-750dafdb-b99a-49b6-883c-3de70916a485@172.17.0.2:47264 with
> resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on
> slave 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 at slave(357)@
> 172.17.0.2:47264 (29ba6f7aca97)
> I0331 17:09:45.403780 1322 slave.cpp:1497] Got assigned task 1 for
> framework 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000
> I0331 17:09:45.404628 1322 slave.cpp:1616] Launching task 1 for framework
> 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000
> I0331 17:09:45.404757 1318 replica.cpp:691] Replica received learned
> notice for position 4 from @0.0.0.0:0
> I0331 17:09:45.405338 1318 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 557752ns
> I0331 17:09:45.405519 1318 leveldb.cpp:399] Deleting ~2 keys from leveldb
> took 132165ns
> I0331 17:09:45.405654 1318 replica.cpp:712] Persisted action at 4
> I0331 17:09:45.405788 1318 replica.cpp:697] Replica learned TRUNCATE
> action at position 4
> I0331 17:09:45.406699 1322 paths.cpp:528] Trying to chown
> '/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/slaves/9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0/frameworks/9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000/executors/default/runs/64ca6446-70ef-4912-98e0-fe9349b5c4f0'
> to user 'mesos'
> I0331 17:09:45.418769 1322 slave.cpp:5575] Launching executor default of
> framework 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000 with resources in work
> directory
> '/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/slaves/9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0/frameworks/9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000/executors/default/runs/64ca6446-70ef-4912-98e0-fe9349b5c4f0'
> I0331 17:09:45.422309 1322 exec.cpp:150] Version: 0.29.0
> I0331 17:09:45.422802 1320 exec.cpp:200] Executor started at:
> executor(102)@172.17.0.2:47264 with pid 1299
> I0331 17:09:45.423281 1322 slave.cpp:1834] Queuing task '1' for executor
> 'default' of framework 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000
> I0331 17:09:45.423583 1322 slave.cpp:881] Successfully attached file
> '/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/slaves/9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0/frameworks/9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000/executors/default/runs/64ca6446-70ef-4912-98e0-fe9349b5c4f0'
> F0331 17:09:45.425576 1322 owned.hpp:110] Check failed: 'get()' Must be
> non NULL
> *** Check failure stack trace: ***
> @ 0x2b84020b04bd google::LogMessage::Fail()
> @ 0x2b84020af89e google::LogMessage::SendToLog()
> @ 0x2b84020b017d google::LogMessage::Flush()
> @ 0x2b84020b35f8 google::LogMessageFatal::~LogMessageFatal()
> @ 0xa07364 google::CheckNotNull<>()
> @ 0x9f69a2 process::Owned<>::operator->()
> @ 0x9f4a6d
> mesos::internal::tests::TestContainerizer::_wait()
> @ 0x9faf66
> _ZN7testing8internal12InvokeHelperIN7process6FutureIN5mesos13containerizer11TerminationEEESt5tupleIJRKNS4_11ContainerIDEEEE12InvokeMethodINS4_8internal5tests17TestContainerizerEMSH_FS7_SB_EEES7_PT_T0_RKSC_
> @ 0x9faeb4
> _ZNK7testing8internal18InvokeMethodActionIN5mesos8internal5tests17TestContainerizerEMS5_FN7process6FutureINS2_13containerizer11TerminationEEERKNS2_11ContainerIDEEE7PerformISA_St5tupleIJSD_EEEET_RKT0_
> @ 0x9fae56
> _ZN7testing17PolymorphicActionINS_8internal18InvokeMethodActionIN5mesos8internal5tests17TestContainerizerEMS6_FN7process6FutureINS3_13containerizer11TerminationEEERKNS3_11ContainerIDEEEEE15MonomorphicImplIFSB_SE_EE7PerformERKSt5tupleIJSE_EE
> @ 0xa30758
> _ZNK7testing6ActionIFN7process6FutureIN5mesos13containerizer11TerminationEEERKNS3_11ContainerIDEEE7PerformERKSt5tupleIJS9_EE
> @ 0xa305ba
> testing::internal::ActionResultHolder<>::PerformAction<>()
> @ 0xa2f19c
> testing::internal::FunctionMockerBase<>::UntypedPerformAction()
> @ 0x197a679
> testing::internal::UntypedFunctionMockerBase::UntypedInvokeWith()
> @ 0xa1d09b
> _ZN7testing8internal18FunctionMockerBaseIFN7process6FutureIN5mesos13containerizer11TerminationEEERKNS4_11ContainerIDEEE10InvokeWithERKSt5tupleIJSA_EE
> @ 0xa1d064 testing::internal::FunctionMocker<>::Invoke()
> @ 0x9f98c0 mesos::internal::tests::TestContainerizer::wait()
> @ 0x2b84012ec6a5 mesos::internal::slave::Slave::executorLaunched()
> @ 0x2b84013225ea
> _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS1_11FrameworkIDERKNS1_10ExecutorIDERKNS1_11ContainerIDERKNS_6FutureIbEES5_S8_SB_SF_EEvRKNS_3PIDIT_EEMSJ_FvT0_T1_T2_T3_ET4_T5_T6_T7_ENKUlPNS_11ProcessBaseEE_clESY_
> @ 0x2b8401322112
> _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchIN5mesos8internal5slave5SlaveERKNS5_11FrameworkIDERKNS5_10ExecutorIDERKNS5_11ContainerIDERKNS0_6FutureIbEES9_SC_SF_SJ_EEvRKNS0_3PIDIT_EEMSN_FvT0_T1_T2_T3_ET4_T5_T6_T7_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_
> @ 0x2b8401fe72e8 std::function<>::operator()()
> @ 0x2b8401fd1534 process::ProcessBase::visit()
> @ 0x2b84020279ae process::DispatchEvent::visit()
> @ 0x86b4f1 process::ProcessBase::serve()
> @ 0x2b8401fcf254 process::ProcessManager::resume()
> @ 0x2b8401fd7375
> process::ProcessManager::init_threads()::$_1::operator()()
> @ 0x2b8401fd72b3
> _ZNSt5_BindIFZN7process14ProcessManager12init_threadsEvE3$_1St17reference_wrapperIKSt11atomic_boolEEE6__callIvJEJLm0EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
> @ 0x2b8401fd7266
> _ZNSt5_BindIFZN7process14ProcessManager12init_threadsEvE3$_1St17reference_wrapperIKSt11atomic_boolEEEclIJEvEET0_DpOT_
> @ 0x2b8401fd7215
> _ZNSt12_Bind_simpleIFSt5_BindIFZN7process14ProcessManager12init_threadsEvE3$_1St17reference_wrapperIKSt11atomic_boolEEEvEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE
> @ 0x2b8401fd71e5 std::_Bind_simple<>::operator()()
> @ 0x2b8401fd71bc std::thread::_Impl<>::_M_run()
> @ 0x2b8403840a60 (unknown)
> make[4]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> make[4]: *** [check-local] Aborted
> make[3]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> make[3]: *** [check-am] Error 2
> make[2]: *** [check] Error 2
> make[2]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> make[1]: *** [check-recursive] Error 1
> make[1]: Leaving directory `/mesos/mesos-0.29.0/_build'
> make: *** [distcheck] Error 1
> + docker rmi mesos-1459442182-6170
> Untagged: mesos-1459442182-6170:latest
> Deleted: 2d66a00ec0049a3861429ddea6ad000ecee3cde2163dbfd00188809a1a4dccbc
> Deleted: 4f22ed5feeb67e6af34b3ca6e647425b7673f368f784fd2002a3d5d691a1984a
> Deleted: b738fe0fba889e16578ed5e4c91da22d32cc3dbee2a4bd6e79bd90a89ab84643
> Deleted: 586be900ed5b534083e54b8111e242c8071a2b27b2354e86f4e59a076937c9d2
> Deleted: 36a17040d363e8c1f1056c63120c4deef43ea9107b8566076780c7b6c2e0814b
> Deleted: 9dddb8f7a4e89a5c43a838920a62116550aa98063927ead99257e8d764a7568a
> Deleted: 26154f0eaffe623d376982559fb226602cc76479bfbb3b25a099f9affc2d1391
> Deleted: 074341c72d94570bd67610567dfd64c082c43c37864089aa1b6893076a881a0e
> Deleted: af36b6d6fc35c9bae6adc732dc4216fce2bbd6d7397d39c290c6ff55c15f32a1
> Deleted: 6e40d728ba2eb6886bca928e9ac9884fd923aa484c33035b7eff7b8a57c70be8
> Deleted: 94f8edab37485281006f5f65690f4571b1ba5012e137b367b1ac0e1f4f733fea
> Deleted: d6955d17738051273ef8c18e66b944879130fabbc936f5f7c502890f93cfe2b3
> Deleted: 13ba435ee0ad83459ba05018be306c55c0acd4211285521ff828db23bdef8528
> Deleted: 89248d43439d536f7b7afa57ae625e859286451f9ef8e49f2d6e97daa6b9d1c6
> Deleted: 841671f9606b1431f94603959fa87c3265735cf1546d3317ad08d1eccedd4401
> Deleted: 7eb2dc32e199b207e33f2e2b658d720e31fba7bd6609078d94254585e3882140
> Build step 'Execute shell' marked build as failure
>