See <https://builds.apache.org/job/Mesos-Reviewbot/23574/display/redirect?page=changes>
Changes: [toenshoff] Allowed for unbundled leveldb in CMake builds. ------------------------------------------ [...truncated 44.64 MB...] I1125 21:48:23.354346 19859 composing.cpp:339] Finished recovering all containerizers I1125 21:48:23.354589 19859 slave.cpp:7143] Recovering executors I1125 21:48:23.354686 19859 slave.cpp:7296] Finished recovery I1125 21:48:23.355676 19859 task_status_update_manager.cpp:181] Pausing sending task status updates I1125 21:48:23.355729 19880 slave.cpp:1259] New master detected at [email protected]:35681 I1125 21:48:23.355875 19880 slave.cpp:1324] Detecting new master I1125 21:48:23.357398 19858 slave.cpp:1351] Authenticating with master [email protected]:35681 I1125 21:48:23.357511 19858 slave.cpp:1360] Using default CRAM-MD5 authenticatee I1125 21:48:23.358001 19861 authenticatee.cpp:121] Creating new client SASL connection I1125 21:48:23.358322 19878 master.cpp:9639] Authenticating slave(46)@172.17.0.2:35681 I1125 21:48:23.358549 19860 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(81)@172.17.0.2:35681 I1125 21:48:23.359145 19875 authenticator.cpp:98] Creating new server SASL connection I1125 21:48:23.359386 19871 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I1125 21:48:23.359417 19871 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I1125 21:48:23.359619 19875 authenticator.cpp:204] Received SASL authentication start I1125 21:48:23.359704 19875 authenticator.cpp:326] Authentication requires more steps I1125 21:48:23.359833 19877 authenticatee.cpp:259] Received SASL authentication step I1125 21:48:23.359969 19875 authenticator.cpp:232] Received SASL authentication step I1125 21:48:23.360019 19875 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'b0a3013467be' server FQDN: 'b0a3013467be' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1125 21:48:23.360054 19875 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I1125 21:48:23.360116 19875 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1125 21:48:23.360158 19875 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'b0a3013467be' server FQDN: 'b0a3013467be' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1125 21:48:23.360190 19875 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1125 21:48:23.360219 19875 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1125 21:48:23.360272 19875 authenticator.cpp:318] Authentication success I1125 21:48:23.360393 19873 authenticatee.cpp:299] Authentication success I1125 21:48:23.363363 19869 master.cpp:9671] Successfully authenticated principal 'test-principal' at slave(46)@172.17.0.2:35681 I1125 21:48:23.363417 19875 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(81)@172.17.0.2:35681 I1125 21:48:23.363430 19870 slave.cpp:1451] Successfully authenticated with master [email protected]:35681 I1125 21:48:23.363886 19870 slave.cpp:1882] Will retry registration in 2.432899ms if necessary I1125 21:48:23.364078 19879 master.cpp:6590] Received register agent message from slave(46)@172.17.0.2:35681 (b0a3013467be) I1125 21:48:23.364475 19879 master.cpp:3930] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal' I1125 21:48:23.365121 19880 master.cpp:6657] Authorized registration of agent at slave(46)@172.17.0.2:35681 (b0a3013467be) I1125 21:48:23.365231 19880 master.cpp:6772] Registering agent at slave(46)@172.17.0.2:35681 (b0a3013467be) with id af2e567e-8c9c-4e78-823a-b3614190a9d9-S0 I1125 21:48:23.366019 19880 registrar.cpp:487] Applied 1 operations in 263157ns; attempting to update the registry I1125 21:48:23.366822 19880 registrar.cpp:544] Successfully updated the registry in 0ns I1125 21:48:23.366997 19871 master.cpp:6820] Admitted agent af2e567e-8c9c-4e78-823a-b3614190a9d9-S0 at slave(46)@172.17.0.2:35681 (b0a3013467be) I1125 21:48:23.367738 19871 master.cpp:6865] Registered agent af2e567e-8c9c-4e78-823a-b3614190a9d9-S0 at slave(46)@172.17.0.2:35681 (b0a3013467be) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] I1125 21:48:23.367761 19877 slave.cpp:1484] Registered with master [email protected]:35681; given agent ID af2e567e-8c9c-4e78-823a-b3614190a9d9-S0 I1125 21:48:23.367892 19859 task_status_update_manager.cpp:188] Resuming sending task status updates I1125 21:48:23.368173 19877 slave.cpp:1504] Checkpointing SlaveInfo to '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentAdd_1_yJngS2/meta/slaves/af2e567e-8c9c-4e78-823a-b3614190a9d9-S0/slave.info' I1125 21:48:23.368276 19862 hierarchical.cpp:603] Added agent af2e567e-8c9c-4e78-823a-b3614190a9d9-S0 (b0a3013467be) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {}) I1125 21:48:23.368579 19862 hierarchical.cpp:1566] Performed allocation for 1 agents in 116365ns I1125 21:48:23.369401 19877 slave.cpp:1553] Forwarding agent update {"operations":{},"resource_version_uuid":{"value":"OLDk9f/1Sx2T0pSTMGY7Lw=="},"slave_id":{"value":"af2e567e-8c9c-4e78-823a-b3614190a9d9-S0"},"update_oversubscribed_resources":false} I1125 21:48:23.370326 19877 master.cpp:7924] Ignoring update on agent af2e567e-8c9c-4e78-823a-b3614190a9d9-S0 at slave(46)@172.17.0.2:35681 (b0a3013467be) as it reports no changes I1125 21:48:23.377599 19860 process.cpp:3588] Handling HTTP event for process 'slave(46)' with path: '/slave(46)/api/v1' I1125 21:48:23.378617 19862 http.cpp:1157] HTTP POST for /slave(46)/api/v1 from 172.17.0.2:47492 I1125 21:48:23.379086 19862 http.cpp:2075] Processing GET_CONTAINERS call I1125 21:48:23.385583 19863 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE' I1125 21:48:23.387954 19860 process.cpp:3588] Handling HTTP event for process 'slave(46)' with path: '/slave(46)/api/v1' I1125 21:48:23.388864 19876 http.cpp:1157] HTTP POST for /slave(46)/api/v1 from 172.17.0.2:47494 I1125 21:48:23.389575 19876 http.cpp:2441] Processing LAUNCH_CONTAINER call for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE' I1125 21:48:23.390458 19869 http.cpp:2545] Creating sandbox '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentAdd_1_yJngS2/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE' I1125 21:48:23.391057 19870 containerizer.cpp:1288] Starting container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE I1125 21:48:23.392542 19870 containerizer.cpp:1454] Checkpointed ContainerConfig at '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentAdd_1_EUfidX/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE/config' I1125 21:48:23.392585 19870 containerizer.cpp:3130] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE from PROVISIONING to PREPARING I1125 21:48:23.396773 19861 containerizer.cpp:1947] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["/mesos/mesos-1.8.0/_build/src/test-csi-plugin","--available_capacity=0B","--volumes=volume1:4GB","--work_dir=/tmp/xNrfx8/test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9"],"shell":false,"value":"/mesos/mesos-1.8.0/_build/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentAdd_1_yJngS2/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE"},{"name":"CSI_ENDPOINT","type":"VALUE","value":"unix:///tmp/mesos-csi-lmQfUA/endpoint.sock"}]},"task_environment":{},"working_directory":"/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentAdd_1_yJngS2/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE"}" --pipe_read="29" --pipe_write="34" --runtime_directory="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentAdd_1_EUfidX/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE" --unshare_namespace_mnt="false"' I1125 21:48:23.401484 19861 launcher.cpp:145] Forked child with pid '32466' for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE' I1125 21:48:23.402084 19861 containerizer.cpp:3130] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE from PREPARING to ISOLATING I1125 21:48:23.403053 19861 containerizer.cpp:3130] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE from ISOLATING to FETCHING I1125 21:48:23.403329 19880 fetcher.cpp:369] Starting to fetch URIs for container: org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE, directory: /tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentAdd_1_yJngS2/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE I1125 21:48:23.404255 19872 containerizer.cpp:3130] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE from FETCHING to RUNNING I1125 21:48:23.406981 19862 container_daemon.cpp:140] Invoking post-start hook for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE' I1125 21:48:23.931565 19879 hierarchical.cpp:1566] Performed allocation for 1 agents in 142657ns I1125 21:48:23.944694 32483 test_csi_plugin.cpp:302] ProbeRequest '{}' I1125 21:48:23.947772 19858 container_daemon.cpp:171] Waiting for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE' I1125 21:48:23.949535 32569 test_csi_plugin.cpp:274] GetPluginInfoRequest '{}' I1125 21:48:23.950927 19860 process.cpp:3588] Handling HTTP event for process 'slave(46)' with path: '/slave(46)/api/v1' I1125 21:48:23.952373 19869 http.cpp:1157] HTTP POST for /slave(46)/api/v1 from 172.17.0.2:47552 I1125 21:48:23.952977 19869 http.cpp:2659] Processing WAIT_CONTAINER call for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE' I1125 21:48:23.958477 19865 provider.cpp:2174] Node plugin loaded: {"name":"org.apache.mesos.csi.test","vendorVersion":"1.8.0"} I1125 21:48:23.960222 32569 test_csi_plugin.cpp:288] GetPluginCapabilitiesRequest '{}' I1125 21:48:23.963014 32569 test_csi_plugin.cpp:274] GetPluginInfoRequest '{}' I1125 21:48:23.964195 19872 provider.cpp:2215] Controller plugin loaded: {"name":"org.apache.mesos.csi.test","vendorVersion":"1.8.0"} I1125 21:48:23.966312 32569 test_csi_plugin.cpp:593] ControllerGetCapabilitiesRequest '{}' I1125 21:48:23.970430 32569 test_csi_plugin.cpp:856] NodeGetCapabilitiesRequest '{}' I1125 21:48:23.973947 32569 test_csi_plugin.cpp:843] NodeGetIdRequest '{}' I1125 21:48:23.975669 19869 provider.cpp:666] Finished recovery for resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test' I1125 21:48:23.975785 19867 status_update_manager_process.hpp:379] Pausing operation status update manager I1125 21:48:23.976166 19870 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:35681/slave(46)/api/v1/resource_provider I1125 21:48:23.978826 19868 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:35681/slave(46)/api/v1/resource_provider I1125 21:48:23.979460 19859 provider.cpp:529] Connected to resource provider manager I1125 21:48:23.980520 19868 http_connection.hpp:131] Sending 1 call to http://172.17.0.2:35681/slave(46)/api/v1/resource_provider I1125 21:48:23.981457 19860 process.cpp:3588] Handling HTTP event for process 'slave(46)' with path: '/slave(46)/api/v1/resource_provider' I1125 21:48:23.982867 19875 http.cpp:1157] HTTP POST for /slave(46)/api/v1/resource_provider from 172.17.0.2:47562 I1125 21:48:23.983453 19869 manager.cpp:736] Subscribing resource provider {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/mesos/mesos-1.8.0/_build/src/test-csi-plugin","--available_capacity=0B","--volumes=volume1:4GB","--work_dir=/tmp/xNrfx8/test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9"],"shell":false,"value":"/mesos/mesos-1.8.0/_build/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9","type":"org.apache.mesos.csi.test"}},"type":"org.apache.mesos.rp.local.storage"} I1125 21:48:23.985384 19864 hierarchical.cpp:1566] Performed allocation for 1 agents in 122644ns I1125 21:48:24.036955 19857 hierarchical.cpp:1566] Performed allocation for 1 agents in 148301ns I1125 21:48:24.067649 19872 slave.cpp:7648] Handling resource provider message 'SUBSCRIBE: {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"f96813f6-84ea-4b78-8475-d1c53be755b5"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/mesos/mesos-1.8.0/_build/src/test-csi-plugin","--available_capacity=0B","--volumes=volume1:4GB","--work_dir=/tmp/xNrfx8/test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9"],"shell":false,"value":"/mesos/mesos-1.8.0/_build/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9","type":"org.apache.mesos.csi.test"}},"type":"org.apache.mesos.rp.local.storage"}' I1125 21:48:24.069026 19862 provider.cpp:551] Received SUBSCRIBED event I1125 21:48:24.070618 19862 provider.cpp:1506] Subscribed with ID f96813f6-84ea-4b78-8475-d1c53be755b5 I1125 21:48:24.071616 19875 status_update_manager_process.hpp:314] Recovering operation status update manager I1125 21:48:24.088070 19874 hierarchical.cpp:1566] Performed allocation for 1 agents in 121251ns I1125 21:48:24.139804 19864 hierarchical.cpp:1566] Performed allocation for 1 agents in 125364ns I1125 21:48:24.190634 19858 hierarchical.cpp:1566] Performed allocation for 1 agents in 120840ns I1125 21:48:24.242316 19865 hierarchical.cpp:1566] Performed allocation for 1 agents in 127865ns I1125 21:48:24.273315 32569 test_csi_plugin.cpp:531] ListVolumesRequest '{}' I1125 21:48:24.275447 19880 provider.cpp:1101] Removing '{}' and adding 'disk(reservations: [(DYNAMIC,storage)])[RAW(volume1,)]:4096' to the total resources I1125 21:48:24.293208 19860 hierarchical.cpp:1566] Performed allocation for 1 agents in 114388ns I1125 21:48:24.344017 19861 hierarchical.cpp:1566] Performed allocation for 1 agents in 142461ns I1125 21:48:24.395037 19877 hierarchical.cpp:1566] Performed allocation for 1 agents in 131632ns I1125 21:48:24.446594 19867 hierarchical.cpp:1566] Performed allocation for 1 agents in 109913ns I1125 21:48:24.453361 19880 provider.cpp:3541] Sending UPDATE_STATE call with resources 'disk(reservations: [(DYNAMIC,storage)])[RAW(volume1,)]:4096' and 0 operations to agent af2e567e-8c9c-4e78-823a-b3614190a9d9-S0 I1125 21:48:24.453855 19880 provider.cpp:1115] Resource provider f96813f6-84ea-4b78-8475-d1c53be755b5 is in READY state I1125 21:48:24.453896 19875 status_update_manager_process.hpp:385] Resuming operation status update manager I1125 21:48:24.453974 19862 http_connection.hpp:131] Sending 3 call to http://172.17.0.2:35681/slave(46)/api/v1/resource_provider I1125 21:48:24.455269 19874 process.cpp:3588] Handling HTTP event for process 'slave(46)' with path: '/slave(46)/api/v1/resource_provider' I1125 21:48:24.457191 19857 http.cpp:1157] HTTP POST for /slave(46)/api/v1/resource_provider from 172.17.0.2:47560 I1125 21:48:24.457886 19869 manager.cpp:917] Received UPDATE_STATE call with resources '[{"disk":{"source":{"id":"volume1","metadata":{"labels":[{"key":"path","value":"/tmp/xNrfx8/test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9/4GB-volume1"}]},"type":"RAW"}},"name":"disk","provider_id":{"value":"f96813f6-84ea-4b78-8475-d1c53be755b5"},"reservations":[{"role":"storage","type":"DYNAMIC"}],"scalar":{"value":4096.0},"type":"SCALAR"}]' and 0 operations from resource provider f96813f6-84ea-4b78-8475-d1c53be755b5 I1125 21:48:24.458472 19879 slave.cpp:7648] Handling resource provider message 'UPDATE_STATE: f96813f6-84ea-4b78-8475-d1c53be755b5 disk(reservations: [(DYNAMIC,storage)])[RAW(volume1,)]:4096' I1125 21:48:24.458639 19879 slave.cpp:7768] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage)])[RAW(volume1,)]:4096 I1125 21:48:24.461235 19859 hierarchical.cpp:746] Grew agent af2e567e-8c9c-4e78-823a-b3614190a9d9-S0 by disk(reservations: [(DYNAMIC,storage)])[RAW(volume1,)]:4096 (total), { } (used) I1125 21:48:24.461561 19859 hierarchical.cpp:703] Agent af2e567e-8c9c-4e78-823a-b3614190a9d9-S0 (b0a3013467be) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage)])[RAW(volume1,)]:4096 I1125 21:48:24.464227 19880 process.cpp:3588] Handling HTTP event for process 'slave(46)' with path: '/slave(46)/api/v1' I1125 21:48:24.465281 19863 http.cpp:1157] HTTP POST for /slave(46)/api/v1 from 172.17.0.2:47612 I1125 21:48:24.467407 19874 http.cpp:3197] Processing ADD_RESOURCE_PROVIDER_CONFIG call with type 'org.apache.mesos.rp.local.storage' and name 'test' I1125 21:48:24.472522 19859 slave.cpp:914] Agent terminating I1125 21:48:24.473196 19859 manager.cpp:161] Terminating resource provider f96813f6-84ea-4b78-8475-d1c53be755b5 I1125 21:48:24.473621 19865 master.cpp:1275] Agent af2e567e-8c9c-4e78-823a-b3614190a9d9-S0 at slave(46)@172.17.0.2:35681 (b0a3013467be) disconnected I1125 21:48:24.473652 19865 master.cpp:3273] Disconnecting agent af2e567e-8c9c-4e78-823a-b3614190a9d9-S0 at slave(46)@172.17.0.2:35681 (b0a3013467be) I1125 21:48:24.473731 19865 master.cpp:3292] Deactivating agent af2e567e-8c9c-4e78-823a-b3614190a9d9-S0 at slave(46)@172.17.0.2:35681 (b0a3013467be) I1125 21:48:24.473892 19878 hierarchical.cpp:801] Agent af2e567e-8c9c-4e78-823a-b3614190a9d9-S0 deactivated E1125 21:48:24.474035 19869 http_connection.hpp:452] End-Of-File received I1125 21:48:24.474802 19869 http_connection.hpp:217] Re-detecting endpoint I1125 21:48:24.475368 19869 http_connection.hpp:338] Ignoring disconnection attempt from stale connection I1125 21:48:24.475371 19870 provider.cpp:541] Disconnected from resource provider manager I1125 21:48:24.475426 19869 http_connection.hpp:338] Ignoring disconnection attempt from stale connection I1125 21:48:24.475517 19869 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:35681/slave(46)/api/v1/resource_provider I1125 21:48:24.475533 19872 status_update_manager_process.hpp:379] Pausing operation status update manager I1125 21:48:24.476976 19860 containerizer.cpp:2463] Destroying container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE in RUNNING state I1125 21:48:24.477015 19860 containerizer.cpp:3130] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE from RUNNING to DESTROYING I1125 21:48:24.477402 19860 launcher.cpp:161] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE I1125 21:48:24.478276 19877 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:35681/slave(46)/api/v1/resource_provider I1125 21:48:24.478785 19876 provider.cpp:529] Connected to resource provider manager I1125 21:48:24.479320 19880 http_connection.hpp:131] Sending 1 call to http://172.17.0.2:35681/slave(46)/api/v1/resource_provider I1125 21:48:24.479948 19881 process.cpp:2754] Returning '404 Not Found' for '/slave(46)/api/v1/resource_provider' E1125 21:48:24.481017 19864 provider.cpp:1072] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Received '404 Not Found' () I1125 21:48:24.500638 19869 hierarchical.cpp:1566] Performed allocation for 1 agents in 173091ns I1125 21:48:24.536989 19865 containerizer.cpp:2969] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE has exited I1125 21:48:24.538445 19861 provisioner.cpp:597] Ignoring destroy request for unknown container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE I1125 21:48:24.541304 19860 container_daemon.cpp:189] Invoking post-stop hook for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE' I1125 21:48:24.541779 19859 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-test_csi_plugin_6e9fc8f2d06a42cba15b9ade723977a9--CONTROLLER_SERVICE-NODE_SERVICE' I1125 21:48:24.544109 19881 process.cpp:2754] Returning '404 Not Found' for '/slave(46)/api/v1' I1125 21:48:24.581861 19281 master.cpp:1117] Master terminating I1125 21:48:24.582984 19875 hierarchical.cpp:643] Removed agent af2e567e-8c9c-4e78-823a-b3614190a9d9-S0 [ OK ] ContentType/AgentResourceProviderConfigApiTest.IdempotentAdd/1 (1270 ms) [----------] 1 test from ContentType/AgentResourceProviderConfigApiTest (1272 ms total) [----------] Global test environment tear-down [==========] 85 tests from 65 test cases ran. (99935 ms total) [ PASSED ] 84 tests. [ FAILED ] 1 test, listed below: [ FAILED ] DockerFetcherPluginTest.INTERNET_CURL_FetchImage 1 FAILED TEST YOU HAVE 24 DISABLED TESTS I1125 21:48:24.697211 19881 process.cpp:927] Stopped the socket accept loop [FAIL]: 1 shard(s) have failed tests 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-1543178478-10317 Untagged: mesos-1543178478-10317:latest Deleted: sha256:fbc1416c06044fc05b79584db9dfb3891ca4984b5ca3e3b7b6b8f2d17f3b39f3 Deleted: sha256:4cb58cb0405d3f02c74a2aeae2f99b1923c3c62d6d7e7e243bd140f257b23f15 Deleted: sha256:fc475fe60503552c3a77a889185b6a33cf10d18409b5cb19a4d33cfed7400955 Deleted: sha256:c8ff185f0496e88716a846e82bae5c550149da0b0e9623924bc55b7019fc986c Deleted: sha256:9df1d7957990db84bd93e37d36ab4bdc7703833a18508b116fda02578e1dee2f Deleted: sha256:99becc52005af79aeb2b6f97deac6e2d9bfde8d436dba1ceb0cefed0259cfd4a Deleted: sha256:510230daa7cdb8c0900b2497fa6653cd85748b99f023d03c077372b06dd88f35 Deleted: sha256:16b85b3a9f322e318d254d41942f10ba68250979db9e3c66e2bfc73634811dd9 Deleted: sha256:b212baafe6948cbcf60d74779ea331a995e2d8d88706fae805038737d062a205 Deleted: sha256:c8e199db8623255eabcc5fc85440de2e67e0907ad462ed1d17cc6535dd233d4a Deleted: sha256:44c3956b6e7f34030d94afcbf87252b46e16abd6f791e7ebdea16758613933da Deleted: sha256:bf186539e602b596a285b8aca244bf361cfcfbeb84fbf53ba86c549b6ab0d5b0 Deleted: sha256:9aa1f7675d5d4c3be992039c029ca7e828684453700e530f52b777ab14a969b0 Deleted: sha256:b58ca84e1adf331f9ed4a550624c52e7525f5ce89e57ff0ff462662f39526230 Deleted: sha256:110b7e46552d408dddd01ef03ad4c13d1aa9f55de013897cc7f84450fadc06a4 Deleted: sha256:8ac5cbf521398f6b6b2ac717f5f5968b6eded401cb1ccaed2efd13ee63cba271 Deleted: sha256:0b90a7d4a5be31b2888d4d6d44081df397484aae0532ccdb63a5845c25652a6e Deleted: sha256:32b9ef59ba4332998cf087ee02d373326eb5a50d3aeb681709007e948b6df145 Deleted: sha256:0071fdf2a7231cbfa8be64f0cbc4687397b6824e046c8df61f916ef991f79420 Deleted: sha256:02a009af08a9a4049da184e3ef77b0a13dbcc4f15495cd9f287a5c00e049e472 Deleted: sha256:afc8b9e21fdbb6b2532e5fc4ddd1581ea7c1ab58a7f6208e1b9fa24615ddc830 Deleted: sha256:f79325d0b869d4df981b817c995a8f4e5ae6081be35e30340b283eb77458213a Deleted: sha256:39467aea6fca97198acdeebe99f49c3b4664c343ef2e76185d15160281c56c0c Deleted: sha256:e602546b53e9731db5127c3d44d6dee7a7b274938cb721698454bc10e650dcc9 Deleted: sha256:e70006260f84d67b4f6431bf6ebbd943fcbacfd6f0b587d4d4204d4ef3846d12 Deleted: sha256:bc6c40336f24ad34fd3cf91a1f35334b9d016a5c91a42336d0d7b263e73c7c82 Deleted: sha256:8e9ec53e676e268c81bd9303395f482950eae7bddebc3f1fd965a1b8cf988f40 Deleted: sha256:4bac3a747ff27e88837098b4d603680da7b6f2aee796a979db7f4c6cf6ecc051 Deleted: sha256:2e3c0ce854575c6a07182845cb594e40aa7db75a09699b270fbd03557e9a5e80 Deleted: sha256:d3dc6819dda9728f53f28059e8a9a7cd6ae7a02c7d0905e462fbdf64e9797422 Deleted: sha256:f17b6a61de28594fb3ec53b1cca7164fba66357d1635b414eeed4d586744342e Deleted: sha256:62faa9fad606573b982c0444778746244947829aa8ebefbf29b3a5291875dc84 Deleted: sha256:5848a5ca21d07333dbdf428bbdde15d5c7cecc7614b24562b49b205d8d20199a Deleted: sha256:cd509aa64a17350b03bf6af7f41d849fc273a0f2c9d1a309e897380617fca46e Deleted: sha256:960c7c5516b277c5c23644b2cfb53d0106543eace96d517141611fa34e1b957c 11-25-18_20:41:12 - Running <https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py> Checking if review 69395 needs verification Latest review timestamp: 2018-11-22 17:30:20 Latest diff timestamp: 2018-11-25 11:16:02 This patch has been updated since its last review, needs verification. Dependent review: https://reviews.apache.org/api/review-requests/69426/ Dependent review: https://reviews.apache.org/api/review-requests/69425/ Dependent review: https://reviews.apache.org/api/review-requests/69394/ Dependent review: https://reviews.apache.org/api/review-requests/69393/ The review request 69393 is already submitted Verifying review 69395 Dependent review: https://reviews.apache.org/api/review-requests/69426/ Dependent review: https://reviews.apache.org/api/review-requests/69425/ Dependent review: https://reviews.apache.org/api/review-requests/69394/ Dependent review: https://reviews.apache.org/api/review-requests/69393/ Applying review 69394 /usr/bin/python3 support/apply-reviews.py -n -r 69394 Applying review 69425 /usr/bin/python3 support/apply-reviews.py -n -r 69425 Applying review 69426 /usr/bin/python3 support/apply-reviews.py -n -r 69426 Applying review 69395 /usr/bin/python3 support/apply-reviews.py -n -r 69395 Traceback (most recent call last): File "<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py";,> line 186, in verify_review % (command, build_output)]) File "/usr/lib/python3.5/subprocess.py", line 581, in check_call raise CalledProcessError(retcode, cmd) subprocess.CalledProcessError: Command '['bash', '-c', "set -o pipefail; export OS='ubuntu:14.04' BUILDTOOL='autotools' COMPILER='gcc' CONFIGURATION='--verbose --disable-libtool-wrappers' ENVIRONMENT='GLOG_v=1 MESOS_VERBOSE=1'; ./support/docker-build.sh 2>&1 | tee build_69395"]' returned non-zero exit status 2 During handling of the above exception, another exception occurred: Traceback (most recent call last): File "<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py";,> line 294, in <module> main() File "<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py";,> line 288, in main verify_review(review_request, handler) File "<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py";,> line 201, in verify_review output = open(build_output).read().decode(sys.stdout.encoding) AttributeError: 'str' object has no attribute 'decode' Build step 'Execute shell' marked build as failure
