From builds-return-22721-archive-asf-public=cust-asf.ponee.io@mesos.apache.org Wed Oct 10 18:08:25 2018 Return-Path: X-Original-To: archive-asf-public@cust-asf.ponee.io Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by mx-eu-01.ponee.io (Postfix) with SMTP id 7100B180672 for ; Wed, 10 Oct 2018 18:08:24 +0200 (CEST) Received: (qmail 41358 invoked by uid 500); 10 Oct 2018 16:08:23 -0000 Mailing-List: contact builds-help@mesos.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: builds@mesos.apache.org Delivered-To: mailing list builds@mesos.apache.org Received: (qmail 41347 invoked by uid 99); 10 Oct 2018 16:08:22 -0000 Received: from mail-relay.apache.org (HELO mailrelay2-lw-us.apache.org) (207.244.88.137) by apache.org (qpsmtpd/0.29) with ESMTP; Wed, 10 Oct 2018 16:08:22 +0000 Received: from jenkins02.apache.org (jenkins02.apache.org [195.201.213.130]) by mailrelay2-lw-us.apache.org (ASF Mail Server at mailrelay2-lw-us.apache.org) with ESMTP id CD3E8469 for ; Wed, 10 Oct 2018 16:08:21 +0000 (UTC) Received: from jenkins02.apache.org (localhost.localdomain [127.0.0.1]) by jenkins02.apache.org (ASF Mail Server at jenkins02.apache.org) with ESMTP id 50D9F33E0145 for ; Wed, 10 Oct 2018 16:08:21 +0000 (UTC) Date: Wed, 10 Oct 2018 16:08:21 +0000 (UTC) From: Apache Jenkins Server To: builds@mesos.apache.org Message-ID: <825225981.1517.1539187701329.JavaMail.jenkins@jenkins02> Subject: Build failed in Jenkins: Mesos-Reviewbot #23469 MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable X-Instance-Identity: MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEAkqVKZPv7YyHBB3FvWfV7XQehwe/Ga3aadzSNknt8g382X3uN8A3SOQ+Ixq9HxS+ZlN6XR4TECySmSRy2JN5Rx8svxAD0TjtSF9LuU98dD+LniNDP7Lq6gvRFuJhbMHoS0nuTizDZLsK4X8TW5MyV9w+jFbdoZfRE5O/Mse0fkOeL5uoIS/3Vvu/W+x9QSjDkB7CaU56bPFlQjqqJBl3Cn9r34CkXQZYnLb/NjW4vcpw0+TgMUAPTIVEr5BTPZRshz19g7huwg3zANT5HBIZnzV4hsVY9w4JHkceFdKi/ibNnjPjsFs9pm0HSGJ/RDxjIvSTYT02eH4+m1RAYaj2E9QIDAQAB X-Jenkins-Job: Mesos-Reviewbot X-Jenkins-Result: FAILURE See Changes: [toenshoff] Fixed JSON object type error when using `support/apply-reviews.= py`. ------------------------------------------ [...truncated 79.31 MB...] I1010 16:08:06.094162 18457 auxprop.cpp:131] Skipping auxiliary property '*= userPassword' since SASL_AUXPROP_AUTHZID =3D=3D true I1010 16:08:06.094260 18457 auxprop.cpp:131] Skipping auxiliary property '*= cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID =3D=3D true I1010 16:08:06.094358 18457 authenticator.cpp:318] Authentication success I1010 16:08:06.094602 18445 authenticatee.cpp:299] Authentication success I1010 16:08:06.095631 18445 master.cpp:9685] Successfully authenticated pri= ncipal 'test-principal' at slave(1062)@172.17.0.2:59657 I1010 16:08:06.095645 18450 slave.cpp:1446] Successfully authenticated with= master master@172.17.0.2:59657 I1010 16:08:06.096968 18450 slave.cpp:1877] Will retry registration in 5.37= 7337ms if necessary I1010 16:08:06.097657 18456 master.cpp:6605] Received register agent messag= e from slave(1062)@172.17.0.2:59657 (dc5f34fed3c2) I1010 16:08:06.097400 18457 authenticator.cpp:432] Authentication session c= leanup for crammd5-authenticatee(1854)@172.17.0.2:59657 I1010 16:08:06.098088 18456 master.cpp:3964] Authorizing agent providing re= sources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal '= test-principal' I1010 16:08:06.099229 18447 master.cpp:6672] Authorized registration of age= nt at slave(1062)@172.17.0.2:59657 (dc5f34fed3c2) I1010 16:08:06.099350 18447 master.cpp:6787] Registering agent at slave(106= 2)@172.17.0.2:59657 (dc5f34fed3c2) with id b49380f9-14a9-4000-9894-19807808= 555d-S0 I1010 16:08:06.100275 18449 registrar.cpp:487] Applied 1 operations in 3274= 38ns; attempting to update the registry I1010 16:08:06.101246 18449 registrar.cpp:544] Successfully updated the reg= istry in 858112ns I1010 16:08:06.101543 18449 master.cpp:6835] Admitted agent b49380f9-14a9-4= 000-9894-19807808555d-S0 at slave(1062)@172.17.0.2:59657 (dc5f34fed3c2) I1010 16:08:06.102542 18449 master.cpp:6880] Registered agent b49380f9-14a9= -4000-9894-19807808555d-S0 at slave(1062)@172.17.0.2:59657 (dc5f34fed3c2) w= ith cpus:2; mem:1024; disk:1024; ports:[31000-32000] I1010 16:08:06.103168 18450 slave.cpp:1479] Registered with master master@1= 72.17.0.2:59657; given agent ID b49380f9-14a9-4000-9894-19807808555d-S0 I1010 16:08:06.103611 18449 hierarchical.cpp:605] Added agent b49380f9-14a9= -4000-9894-19807808555d-S0 (dc5f34fed3c2) with cpus:2; mem:1024; disk:1024;= ports:[31000-32000] (allocated: {}) I1010 16:08:06.104164 18444 task_status_update_manager.cpp:188] Resuming se= nding task status updates I1010 16:08:06.104496 18449 hierarchical.cpp:1568] Performed allocation for= 1 agents in 130520ns I1010 16:08:06.104991 18450 slave.cpp:1499] Checkpointing SlaveInfo to '/tm= p/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_pUKgZ4/= meta/slaves/b49380f9-14a9-4000-9894-19807808555d-S0/slave.info' I1010 16:08:06.109246 18450 slave.cpp:1548] Forwarding agent update {"opera= tions":{},"resource_version_uuid":{"value":"5ehOlAy7Q2Co7CpBSJubEQ=3D=3D"},= "slave_id":{"value":"b49380f9-14a9-4000-9894-19807808555d-S0"},"update_over= subscribed_resources":false} I1010 16:08:06.111672 18450 master.cpp:7939] Ignoring update on agent b4938= 0f9-14a9-4000-9894-19807808555d-S0 at slave(1062)@172.17.0.2:59657 (dc5f34f= ed3c2) as it reports no changes I1010 16:08:06.112080 18447 process.cpp:3569] Handling HTTP event for proce= ss 'slave(1062)' with path: '/slave(1062)/api/v1' I1010 16:08:06.115172 18445 http.cpp:1177] HTTP POST for /slave(1062)/api/v= 1 from 172.17.0.2:37944 I1010 16:08:06.117079 18457 http.cpp:3297] Processing REMOVE_RESOURCE_PROVI= DER_CONFIG call with type 'org.apache.mesos.rp.local.storage' and name 'tes= t' I1010 16:08:06.120604 18442 slave.cpp:909] Agent terminating I1010 16:08:06.126924 18446 hierarchical.cpp:1568] Performed allocation for= 1 agents in 155009ns I1010 16:08:06.146776 18457 master.cpp:1251] Agent b49380f9-14a9-4000-9894-= 19807808555d-S0 at slave(1062)@172.17.0.2:59657 (dc5f34fed3c2) disconnected I1010 16:08:06.146843 18457 master.cpp:3267] Disconnecting agent b49380f9-1= 4a9-4000-9894-19807808555d-S0 at slave(1062)@172.17.0.2:59657 (dc5f34fed3c2= ) I1010 16:08:06.146951 18457 master.cpp:3286] Deactivating agent b49380f9-14= a9-4000-9894-19807808555d-S0 at slave(1062)@172.17.0.2:59657 (dc5f34fed3c2) I1010 16:08:06.147343 18457 hierarchical.cpp:803] Agent b49380f9-14a9-4000-= 9894-19807808555d-S0 deactivated I1010 16:08:06.183105 18448 hierarchical.cpp:1568] Performed allocation for= 1 agents in 139124ns I1010 16:08:06.211954 18442 master.cpp:1093] Master terminating I1010 16:08:06.212996 18444 hierarchical.cpp:645] Removed agent b49380f9-14= a9-4000-9894-19807808555d-S0 I1010 16:08:06.234781 18443 hierarchical.cpp:1568] Performed allocation for= 0 agents in 95009ns [ OK ] ContentType/AgentResourceProviderConfigApiTest.IdempotentRemov= e/0 (245 ms) [ RUN ] ContentType/AgentResourceProviderConfigApiTest.IdempotentRemov= e/1 I1010 16:08:06.243299 18442 cluster.cpp:173] Creating default 'local' autho= rizer I1010 16:08:06.253813 18451 master.cpp:413] Master 529322aa-b6db-464a-9bcd-= 82a6fb46713f (dc5f34fed3c2) started on 172.17.0.2:59657 I1010 16:08:06.253885 18451 master.cpp:416] Flags at startup: --acls=3D"" -= -agent_ping_timeout=3D"15secs" --agent_reregister_timeout=3D"10mins" --allo= cation_interval=3D"50ms" --allocator=3D"hierarchical" --authenticate_agents= =3D"true" --authenticate_frameworks=3D"true" --authenticate_http_frameworks= =3D"true" --authenticate_http_readonly=3D"true" --authenticate_http_readwri= te=3D"true" --authentication_v0_timeout=3D"15secs" --authenticators=3D"cram= md5" --authorizers=3D"local" --credentials=3D"/tmp/Vd1bZY/credentials" --fi= lter_gpu_resources=3D"true" --framework_sorter=3D"drf" --help=3D"false" --h= ostname_lookup=3D"true" --http_authenticators=3D"basic" --http_framework_au= thenticators=3D"basic" --initialize_driver_logging=3D"true" --log_auto_init= ialize=3D"true" --logbufsecs=3D"0" --logging_level=3D"INFO" --max_agent_pin= g_timeouts=3D"5" --max_completed_frameworks=3D"50" --max_completed_tasks_pe= r_framework=3D"1000" --max_unreachable_tasks_per_framework=3D"1000" --memor= y_profiling=3D"false" --min_allocatable_resources=3D"cpus:0.01|mem:32" --po= rt=3D"5050" --quiet=3D"false" --recovery_agent_removal_limit=3D"100%" --reg= istry=3D"in_memory" --registry_fetch_timeout=3D"1mins" --registry_gc_interv= al=3D"15mins" --registry_max_agent_age=3D"2weeks" --registry_max_agent_coun= t=3D"102400" --registry_store_timeout=3D"100secs" --registry_strict=3D"fals= e" --require_agent_domain=3D"false" --role_sorter=3D"drf" --root_submission= s=3D"true" --version=3D"false" --webui_dir=3D"/mesos/mesos-1.8.0/_inst/shar= e/mesos/webui" --work_dir=3D"/tmp/Vd1bZY/master" --zk_session_timeout=3D"10= secs" I1010 16:08:06.257557 18451 master.cpp:465] Master only allowing authentica= ted frameworks to register I1010 16:08:06.257591 18451 master.cpp:471] Master only allowing authentica= ted agents to register I1010 16:08:06.257606 18451 master.cpp:477] Master only allowing authentica= ted HTTP frameworks to register I1010 16:08:06.257620 18451 credentials.hpp:37] Loading credentials for aut= hentication from '/tmp/Vd1bZY/credentials' I1010 16:08:06.258065 18451 master.cpp:521] Using default 'crammd5' authent= icator I1010 16:08:06.258697 18451 http.cpp:1037] Creating default 'basic' HTTP au= thenticator for realm 'mesos-master-readonly' I1010 16:08:06.258949 18451 http.cpp:1037] Creating default 'basic' HTTP au= thenticator for realm 'mesos-master-readwrite' I1010 16:08:06.259284 18451 http.cpp:1037] Creating default 'basic' HTTP au= thenticator for realm 'mesos-master-scheduler' I1010 16:08:06.259994 18451 master.cpp:602] Authorization enabled I1010 16:08:06.260694 18453 hierarchical.cpp:182] Initialized hierarchical = allocator process I1010 16:08:06.260996 18450 whitelist_watcher.cpp:77] No whitelist given I1010 16:08:06.270584 18456 master.cpp:2083] Elected as the leading master! I1010 16:08:06.270650 18456 master.cpp:1638] Recovering from registrar I1010 16:08:06.271020 18456 registrar.cpp:339] Recovering registrar I1010 16:08:06.272318 18456 registrar.cpp:383] Successfully fetched the reg= istry (0B) in 1.242368ms I1010 16:08:06.272506 18456 registrar.cpp:487] Applied 1 operations in 5237= 5ns; attempting to update the registry I1010 16:08:06.273581 18456 registrar.cpp:544] Successfully updated the reg= istry in 990208ns I1010 16:08:06.273742 18456 registrar.cpp:416] Successfully recovered regis= trar I1010 16:08:06.274458 18456 master.cpp:1752] Recovered 0 agents from the re= gistry (135B); allowing 10mins for agents to reregister I1010 16:08:06.274715 18450 hierarchical.cpp:220] Skipping recovery of hier= archical allocator: nothing to recover W1010 16:08:06.285090 18442 process.cpp:2810] Attempted to spawn already ru= nning process files@172.17.0.2:59657 I1010 16:08:06.287912 18442 containerizer.cpp:305] Using isolation { enviro= nment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } W1010 16:08:06.288748 18442 backend.cpp:76] Failed to create 'aufs' backend= : AufsBackend requires root privileges W1010 16:08:06.288947 18442 backend.cpp:76] Failed to create 'bind' backend= : BindBackend requires root privileges I1010 16:08:06.289001 18442 provisioner.cpp:298] Using default backend 'cop= y' I1010 16:08:06.292421 18442 cluster.cpp:485] Creating default 'local' autho= rizer I1010 16:08:06.300709 18449 slave.cpp:267] Mesos agent started on (1063)@17= 2.17.0.2:59657 I1010 16:08:06.300762 18449 slave.cpp:268] Flags at startup: --acls=3D"" --= appc_simple_discovery_uri_prefix=3D"http://" --appc_store_dir=3D"/tmp/Conte= ntType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_BLxmRW/store/a= ppc" --authenticate_http_readonly=3D"true" --authenticate_http_readwrite=3D= "false" --authenticatee=3D"crammd5" --authentication_backoff_factor=3D"1sec= s" --authentication_timeout_max=3D"1mins" --authentication_timeout_min=3D"5= secs" --authorizer=3D"local" --cgroups_cpu_enable_pids_and_tids_count=3D"fa= lse" --cgroups_destroy_timeout=3D"1mins" --cgroups_enable_cfs=3D"false" --c= groups_hierarchy=3D"/sys/fs/cgroup" --cgroups_limit_swap=3D"false" --cgroup= s_root=3D"mesos" --container_disk_watch_interval=3D"15secs" --containerizer= s=3D"mesos" --credential=3D"/tmp/ContentType_AgentResourceProviderConfigApi= Test_IdempotentRemove_1_BLxmRW/credential" --default_role=3D"*" --disallow_= sharing_agent_pid_namespace=3D"false" --disk_watch_interval=3D"1mins" --doc= ker=3D"docker" --docker_kill_orphans=3D"true" --docker_registry=3D"https://= registry-1.docker.io" --docker_remove_delay=3D"6hrs" --docker_socket=3D"/va= r/run/docker.sock" --docker_stop_timeout=3D"0ns" --docker_store_dir=3D"/tmp= /ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_BLxmRW/s= tore/docker" --docker_volume_checkpoint_dir=3D"/var/run/mesos/isolators/doc= ker/volume" --enforce_container_disk_quota=3D"false" --executor_registratio= n_timeout=3D"1mins" --executor_reregistration_timeout=3D"2secs" --executor_= shutdown_grace_period=3D"5secs" --fetcher_cache_dir=3D"/tmp/ContentType_Age= ntResourceProviderConfigApiTest_IdempotentRemove_1_BLxmRW/fetch" --fetcher_= cache_size=3D"2GB" --fetcher_stall_timeout=3D"1mins" --frameworks_home=3D""= --gc_delay=3D"1weeks" --gc_disk_headroom=3D"0.1" --gc_non_executor_contain= er_sandboxes=3D"false" --help=3D"false" --hostname_lookup=3D"true" --http_c= ommand_executor=3D"false" --http_credentials=3D"/tmp/ContentType_AgentResou= rceProviderConfigApiTest_IdempotentRemove_1_BLxmRW/http_credentials" --http= _heartbeat_interval=3D"30secs" --initialize_driver_logging=3D"true" --isola= tion=3D"posix/cpu,posix/mem" --launcher=3D"posix" --launcher_dir=3D"/mesos/= mesos-1.8.0/_build/src" --logbufsecs=3D"0" --logging_level=3D"INFO" --max_c= ompleted_executors_per_framework=3D"150" --memory_profiling=3D"false" --net= work_cni_metrics=3D"true" --oversubscribed_resources_interval=3D"15secs" --= perf_duration=3D"10secs" --perf_interval=3D"1mins" --port=3D"5051" --qos_co= rrection_interval_min=3D"0ns" --quiet=3D"false" --reconfiguration_policy=3D= "equal" --recover=3D"reconnect" --recovery_timeout=3D"15mins" --registratio= n_backoff_factor=3D"10ms" --resource_provider_config_dir=3D"/tmp/Vd1bZY/res= ource_provider_configs" --resources=3D"cpus:2;gpus:0;mem:1024;disk:1024;por= ts:[31000-32000]" --revocable_cpu_low_priority=3D"true" --runtime_dir=3D"/t= mp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_BLxmRW= " --sandbox_directory=3D"/mnt/mesos/sandbox" --strict=3D"true" --switch_use= r=3D"true" --systemd_enable_support=3D"true" --systemd_runtime_directory=3D= "/run/systemd/system" --version=3D"false" --work_dir=3D"/tmp/ContentType_Ag= entResourceProviderConfigApiTest_IdempotentRemove_1_w9mgVX" --zk_session_ti= meout=3D"10secs" I1010 16:08:06.301533 18449 credentials.hpp:86] Loading credential for auth= entication from '/tmp/ContentType_AgentResourceProviderConfigApiTest_Idempo= tentRemove_1_BLxmRW/credential' I1010 16:08:06.301801 18449 slave.cpp:300] Agent using credential for: test= -principal I1010 16:08:06.301836 18449 credentials.hpp:37] Loading credentials for aut= hentication from '/tmp/ContentType_AgentResourceProviderConfigApiTest_Idemp= otentRemove_1_BLxmRW/http_credentials' I1010 16:08:06.302161 18449 http.cpp:1037] Creating default 'basic' HTTP au= thenticator for realm 'mesos-agent-readonly' I1010 16:08:06.302670 18449 disk_profile_adaptor.cpp:80] Creating default d= isk profile adaptor module I1010 16:08:06.304700 18449 slave.cpp:615] Agent resources: [{"name":"cpus"= ,"scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":10= 24.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCA= LAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"typ= e":"RANGES"}] I1010 16:08:06.305011 18449 slave.cpp:623] Agent attributes: [ ] I1010 16:08:06.305040 18449 slave.cpp:632] Agent hostname: dc5f34fed3c2 I1010 16:08:06.305550 18455 task_status_update_manager.cpp:181] Pausing sen= ding task status updates I1010 16:08:06.309109 18457 state.cpp:66] Recovering state from '/tmp/Conte= ntType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_w9mgVX/meta' I1010 16:08:06.315088 18452 hierarchical.cpp:1568] Performed allocation for= 0 agents in 97587ns I1010 16:08:06.318656 18449 slave.cpp:6909] Finished recovering checkpointe= d state from '/tmp/ContentType_AgentResourceProviderConfigApiTest_Idempoten= tRemove_1_w9mgVX/meta', beginning agent recovery I1010 16:08:06.318894 18447 task_status_update_manager.cpp:207] Recovering = task status update manager I1010 16:08:06.319454 18455 containerizer.cpp:727] Recovering Mesos contain= ers I1010 16:08:06.319898 18455 containerizer.cpp:1053] Recovering isolators I1010 16:08:06.321853 18455 containerizer.cpp:1092] Recovering provisioner I1010 16:08:06.322902 18455 provisioner.cpp:494] Provisioner recovery compl= ete I1010 16:08:06.324345 18446 composing.cpp:339] Finished recovering all cont= ainerizers I1010 16:08:06.326328 18452 slave.cpp:7138] Recovering executors I1010 16:08:06.326542 18452 slave.cpp:7291] Finished recovery I1010 16:08:06.334578 18452 slave.cpp:1254] New master detected at master@1= 72.17.0.2:59657 I1010 16:08:06.334830 18452 slave.cpp:1319] Detecting new master I1010 16:08:06.335155 18452 task_status_update_manager.cpp:181] Pausing sen= ding task status updates I1010 16:08:06.346336 18447 slave.cpp:1346] Authenticating with master mast= er@172.17.0.2:59657 I1010 16:08:06.346560 18447 slave.cpp:1355] Using default CRAM-MD5 authenti= catee I1010 16:08:06.347380 18447 authenticatee.cpp:121] Creating new client SASL= connection I1010 16:08:06.347893 18447 master.cpp:9653] Authenticating slave(1063)@172= .17.0.2:59657 I1010 16:08:06.348178 18447 authenticator.cpp:414] Starting authentication = session for crammd5-authenticatee(1855)@172.17.0.2:59657 I1010 16:08:06.348723 18447 authenticator.cpp:98] Creating new server SASL = connection I1010 16:08:06.349066 18447 authenticatee.cpp:213] Received SASL authentica= tion mechanisms: CRAM-MD5 I1010 16:08:06.349103 18447 authenticatee.cpp:239] Attempting to authentica= te with mechanism 'CRAM-MD5' I1010 16:08:06.349247 18447 authenticator.cpp:204] Received SASL authentica= tion start I1010 16:08:06.349331 18447 authenticator.cpp:326] Authentication requires = more steps I1010 16:08:06.349472 18447 authenticatee.cpp:259] Received SASL authentica= tion step I1010 16:08:06.349622 18447 authenticator.cpp:232] Received SASL authentica= tion step I1010 16:08:06.349663 18447 auxprop.cpp:109] Request to lookup properties f= or user: 'test-principal' realm: 'dc5f34fed3c2' server FQDN: 'dc5f34fed3c2'= SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_= AUXPROP_AUTHZID: false=20 I1010 16:08:06.349691 18447 auxprop.cpp:181] Looking up auxiliary property = '*userPassword' I1010 16:08:06.349758 18447 auxprop.cpp:181] Looking up auxiliary property = '*cmusaslsecretCRAM-MD5' I1010 16:08:06.349807 18447 auxprop.cpp:109] Request to lookup properties f= or user: 'test-principal' realm: 'dc5f34fed3c2' server FQDN: 'dc5f34fed3c2'= SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_= AUXPROP_AUTHZID: true=20 I1010 16:08:06.349835 18447 auxprop.cpp:131] Skipping auxiliary property '*= userPassword' since SASL_AUXPROP_AUTHZID =3D=3D true I1010 16:08:06.349859 18447 auxprop.cpp:131] Skipping auxiliary property '*= cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID =3D=3D true I1010 16:08:06.349891 18447 authenticator.cpp:318] Authentication success I1010 16:08:06.350214 18451 authenticatee.cpp:299] Authentication success I1010 16:08:06.350455 18453 master.cpp:9685] Successfully authenticated pri= ncipal 'test-principal' at slave(1063)@172.17.0.2:59657 I1010 16:08:06.350718 18448 authenticator.cpp:432] Authentication session c= leanup for crammd5-authenticatee(1855)@172.17.0.2:59657 I1010 16:08:06.351450 18453 slave.cpp:1446] Successfully authenticated with= master master@172.17.0.2:59657 I1010 16:08:06.352010 18453 slave.cpp:1877] Will retry registration in 17.2= 87332ms if necessary I1010 16:08:06.352475 18453 master.cpp:6605] Received register agent messag= e from slave(1063)@172.17.0.2:59657 (dc5f34fed3c2) I1010 16:08:06.352895 18453 master.cpp:3964] Authorizing agent providing re= sources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal '= test-principal' I1010 16:08:06.354917 18453 master.cpp:6672] Authorized registration of age= nt at slave(1063)@172.17.0.2:59657 (dc5f34fed3c2) I1010 16:08:06.355080 18453 master.cpp:6787] Registering agent at slave(106= 3)@172.17.0.2:59657 (dc5f34fed3c2) with id 529322aa-b6db-464a-9bcd-82a6fb46= 713f-S0 I1010 16:08:06.356070 18453 registrar.cpp:487] Applied 1 operations in 3317= 07ns; attempting to update the registry I1010 16:08:06.357234 18453 registrar.cpp:544] Successfully updated the reg= istry in 1.062912ms I1010 16:08:06.357666 18453 master.cpp:6835] Admitted agent 529322aa-b6db-4= 64a-9bcd-82a6fb46713f-S0 at slave(1063)@172.17.0.2:59657 (dc5f34fed3c2) I1010 16:08:06.359506 18444 hierarchical.cpp:605] Added agent 529322aa-b6db= -464a-9bcd-82a6fb46713f-S0 (dc5f34fed3c2) with cpus:2; mem:1024; disk:1024;= ports:[31000-32000] (allocated: {}) I1010 16:08:06.359896 18444 hierarchical.cpp:1568] Performed allocation for= 1 agents in 132603ns I1010 16:08:06.360311 18458 slave.cpp:1479] Registered with master master@1= 72.17.0.2:59657; given agent ID 529322aa-b6db-464a-9bcd-82a6fb46713f-S0 I1010 16:08:06.360777 18458 slave.cpp:1499] Checkpointing SlaveInfo to '/tm= p/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_w9mgVX/= meta/slaves/529322aa-b6db-464a-9bcd-82a6fb46713f-S0/slave.info' I1010 16:08:06.362246 18443 task_status_update_manager.cpp:188] Resuming se= nding task status updates I1010 16:08:06.363036 18458 slave.cpp:1548] Forwarding agent update {"opera= tions":{},"resource_version_uuid":{"value":"K+byK96CSvWzuws4fQnxvw=3D=3D"},= "slave_id":{"value":"529322aa-b6db-464a-9bcd-82a6fb46713f-S0"},"update_over= subscribed_resources":false} I1010 16:08:06.363570 18453 master.cpp:6880] Registered agent 529322aa-b6db= -464a-9bcd-82a6fb46713f-S0 at slave(1063)@172.17.0.2:59657 (dc5f34fed3c2) w= ith cpus:2; mem:1024; disk:1024; ports:[31000-32000] I1010 16:08:06.364511 18453 master.cpp:7939] Ignoring update on agent 52932= 2aa-b6db-464a-9bcd-82a6fb46713f-S0 at slave(1063)@172.17.0.2:59657 (dc5f34f= ed3c2) as it reports no changes I1010 16:08:06.366910 18452 hierarchical.cpp:1568] Performed allocation for= 1 agents in 156029ns I1010 16:08:06.374434 18451 process.cpp:3569] Handling HTTP event for proce= ss 'slave(1063)' with path: '/slave(1063)/api/v1' I1010 16:08:06.376901 18456 http.cpp:1177] HTTP POST for /slave(1063)/api/v= 1 from 172.17.0.2:37945 I1010 16:08:06.379418 18456 http.cpp:3297] Processing REMOVE_RESOURCE_PROVI= DER_CONFIG call with type 'org.apache.mesos.rp.local.storage' and name 'tes= t' I1010 16:08:06.406473 18447 slave.cpp:909] Agent terminating I1010 16:08:06.417013 18456 master.cpp:1251] Agent 529322aa-b6db-464a-9bcd-= 82a6fb46713f-S0 at slave(1063)@172.17.0.2:59657 (dc5f34fed3c2) disconnected I1010 16:08:06.417088 18456 master.cpp:3267] Disconnecting agent 529322aa-b= 6db-464a-9bcd-82a6fb46713f-S0 at slave(1063)@172.17.0.2:59657 (dc5f34fed3c2= ) I1010 16:08:06.417170 18456 master.cpp:3286] Deactivating agent 529322aa-b6= db-464a-9bcd-82a6fb46713f-S0 at slave(1063)@172.17.0.2:59657 (dc5f34fed3c2) I1010 16:08:06.417493 18456 hierarchical.cpp:803] Agent 529322aa-b6db-464a-= 9bcd-82a6fb46713f-S0 deactivated I1010 16:08:06.418438 18446 hierarchical.cpp:1568] Performed allocation for= 1 agents in 122498ns I1010 16:08:06.474855 18454 hierarchical.cpp:1568] Performed allocation for= 1 agents in 141679ns I1010 16:08:06.531014 18447 hierarchical.cpp:1568] Performed allocation for= 1 agents in 134199ns I1010 16:08:06.560992 18454 master.cpp:1093] Master terminating I1010 16:08:06.562116 18458 hierarchical.cpp:645] Removed agent 529322aa-b6= db-464a-9bcd-82a6fb46713f-S0 I1010 16:08:06.586509 18456 hierarchical.cpp:1568] Performed allocation for= 0 agents in 94149ns [ OK ] ContentType/AgentResourceProviderConfigApiTest.IdempotentRemov= e/1 (354 ms) [----------] 20 tests from ContentType/AgentResourceProviderConfigApiTest (= 17146 ms total) [----------] Global test environment tear-down [=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D] 2047 tests from 206 test cases ran. (74400= 4 ms total) [ PASSED ] 2046 tests. [ FAILED ] 1 test, listed below: [ FAILED ] StorageLocalResourceProviderTest.RetryOperationStatusUpdateToS= cheduler 1 FAILED TEST YOU HAVE 25 DISABLED TESTS I1010 16:08:06.794695 18459 process.cpp:926] Stopped the socket accept loop make[4]: *** [check-local] Error 1 make[4]: Leaving directory `/mesos/mesos-1.8.0/_build/src' make[3]: *** [check-am] Error 2 make[3]: Leaving directory `/mesos/mesos-1.8.0/_build/src' make[2]: *** [check] Error 2 make[2]: Leaving directory `/mesos/mesos-1.8.0/_build/src' make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `/mesos/mesos-1.8.0/_build' make: *** [distcheck] Error 1 + docker rmi --force mesos-1539182820-12112 Untagged: mesos-1539182820-12112:latest Deleted: sha256:ab94a24daf09fd41236a0e081773381d2e0295834598cb749ee74abf56b= 2677e Deleted: sha256:1ff85eb2a2ed7f42e5b7dfea399b56240f847ba88c11e34975736373348= 006f0 Deleted: sha256:3fde7ed7792b8d31bdb9f7b70bb2beea47a21cffb6a40ba58b79cfcca4e= 3db88 Deleted: sha256:f47db871d6d13a0ed547400aaadc4e92c0e7605739c3d28a4c97cfd4fe3= 81eef Deleted: sha256:402d16c58b129003749aff0dbeb4f1b1e18c90ae619f343136575713e3e= bb205 Deleted: sha256:86db2b14b0c0d3431b5320112c43ff361782fe9ae7edf7f7aa066913f3f= 961d2 Deleted: sha256:ccfd4df63c3b993d69c5f6834d9830d4c5cd30d9d4942e398e6854806e0= 21c53 Deleted: sha256:ac34f85c720caa0623f9399fa00215e9982167f518f18d18a943560cee0= f11c1 Deleted: sha256:3dc43fda70503bfcc101c2bdef4a741ebed74924a36432340b9c4bda1b9= 4a173 Deleted: sha256:2c78c3f4ccca797f0c82b8812624adb263da0cdb182bbd56131b682c3a8= 513a0 Deleted: sha256:e7c8fe719880b1fae3f7e8a411a1c71951db1446386f7f8556a80b76b01= 1ec2a Deleted: sha256:cc2af40513c7985ecb3b678fe31dada12a0bf31b4c077267c3ff4fbe8a2= 6e62f Deleted: sha256:6ba1b2e92019e7c1d4abf92fb4cd351f9ce4fcd10c340dd19884379ff70= 02f73 Deleted: sha256:30c97f188906a43a00cd31af563e1c3543b3272af09b8557a2e0f0ed758= 623e8 Deleted: sha256:cde0652f99744bf1ee208c737b07e42f221bd4fbaaef77170365f435f4f= 648f7 Deleted: sha256:7a7605c8b60a0f0652fbfb8059909e90c0c853d39ff325e3498f5169410= f2d30 Deleted: sha256:f81c3a7f452dd22200d0623eb8bc53d1417d2434490ef18af3b82eef339= c278a Deleted: sha256:707a54b7071b0e2f72d354f18150689b486219d0679190d2e22f0e79de6= 7e0f3 Deleted: sha256:7db6215f2c866e9e18f2f7009c50be12cea7f5d74f98ba79d763485e35f= f27e9 Deleted: sha256:fd7296ca8ec4e78bfc3bb01c0f2a9e7a73ff307e3c4b2c75af34f1c6158= 66550 Deleted: sha256:fd8d0648c4374d82f6697ed7ec7053fd134c672e52cd4fbb9597f3ad7ff= c76cc Deleted: sha256:20b60527fb372dd0e44165731144f23a69c65528bf6b5cf2825e946c45c= aae73 Deleted: sha256:f726c88fb25f2cb17591cdf8ad458821d78264a7a61a5a0369639988eed= 56c24 Deleted: sha256:12f551e8aa1c9a605018a268f251a9912aa755ea62127a982ffadeaf255= 8aabe Deleted: sha256:eb515f91429e665471c4e8125868f676537cf1d5fea62a4365980172df6= 429e0 Deleted: sha256:4c02d6e824306a4546d4c2eace97eb62e2c37c357435ccf9129530ec3e4= 265ec Deleted: sha256:0aaf170e0bcf23b41ac4bc9582a43eb9447580a235fe5f5c0104511e989= f1e95 Deleted: sha256:5df6ca83538d7a52ff5490f53a803a79be22c2609a0ba4f7bf3a01dec4d= 1aeaf Deleted: sha256:557f62396222ef99c17e6bd024b38cef60e292496e8cddea387df717043= 25d9c Deleted: sha256:6be72047010b053fd66090c9d2fb087d980a9587234b770eff176b62cde= 9bd7f Deleted: sha256:c32fae490809105445a55e73dc76f78f6f91244bd11a05f050ef93e7830= a4be1 Deleted: sha256:110825ef56c88b9f83d1679e2ab316e63cd672dbe2a17426aef2458c777= 415ed Deleted: sha256:95c7c9b62b1f21943a5b13d1120a674111b779d42dc33289ec4e06cb288= 99c3d Deleted: sha256:ccff0b2ed3204d73c3e3d7410c8f8ca5ab51b455a26272bcb97aab120c8= 8afb5 Deleted: sha256:1fcd35c146a62e40a9f79240d1c5830b6a88f6a56f077860f7a824732e2= adbdb Deleted: sha256:aa4e7d478f39c9327a0c4872b858418020a01074f5deebff9526af00374= 54dc0 10-10-18_14:46:54 - Running Checking if review 68971 needs verification Skipping blocking review 68971 Checking if review 68976 needs verification Skipping blocking review 68976 Checking if review 68977 needs verification Skipping blocking review 68977 Checking if review 68978 needs verification Patch never verified, needs verification Dependent review: https://reviews.apache.org/api/review-requests/68977/ Dependent review: https://reviews.apache.org/api/review-requests/68976/ Dependent review: https://reviews.apache.org/api/review-requests/68971/ Verifying review 68978 Dependent review: https://reviews.apache.org/api/review-requests/68977/ Dependent review: https://reviews.apache.org/api/review-requests/68976/ Dependent review: https://reviews.apache.org/api/review-requests/68971/ Applying review 68971 /usr/bin/python3 support/apply-reviews.py -n -r 68971 Applying review 68976 /usr/bin/python3 support/apply-reviews.py -n -r 68976 Applying review 68977 /usr/bin/python3 support/apply-reviews.py -n -r 68977 Applying review 68978 /usr/bin/python3 support/apply-reviews.py -n -r 68978 Traceback (most recent call last): File " line 186, in verify_review % (command, build_output)]) File "/usr/lib/python3.4/subprocess.py", line 561, in check_call raise CalledProcessError(retcode, cmd) subprocess.CalledProcessError: Command '['bash', '-c', "set -o pipefail; ex= port OS=3D'ubuntu:14.04' BUILDTOOL=3D'autotools' COMPILER=3D'gcc' CONFIGURA= TION=3D'--verbose --disable-libtool-wrappers' ENVIRONMENT=3D'GLOG_v=3D1 MES= OS_VERBOSE=3D1'; ./support/docker-build.sh 2>&1 | tee build_68978"]' return= ed non-zero exit status 2 During handling of the above exception, another exception occurred: Traceback (most recent call last): File " line 294, in main() File " line 288, in main verify_review(review_request, handler) File " line 201, in verify_review output =3D open(build_output).read().decode(sys.stdout.encoding) AttributeError: 'str' object has no attribute 'decode' Build step 'Execute shell' marked build as failure