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 2017/12/27 23:16:19 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4675
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4675/display/redirect?page=changes>
Changes:
[yujie.jay] Fixed resource provider driver disconnection handling.
------------------------------------------
[...truncated 4.98 MB...]
3: I1227 23:16:00.153304 16063 slave.cpp:1137] Authenticating with master master@172.17.0.4:46256
3: I1227 23:16:00.153363 16063 slave.cpp:1146] Using default CRAM-MD5 authenticatee
3: I1227 23:16:00.153616 16074 authenticatee.cpp:121] Creating new client SASL connection
3: I1227 23:16:00.153919 16084 master.cpp:8928] Authenticating slave(95)@172.17.0.4:46256
3: I1227 23:16:00.154079 16076 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(199)@172.17.0.4:46256
3: I1227 23:16:00.154345 16077 authenticator.cpp:98] Creating new server SASL connection
3: I1227 23:16:00.154599 16082 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1227 23:16:00.154626 16082 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1227 23:16:00.154742 16075 authenticator.cpp:204] Received SASL authentication start
3: I1227 23:16:00.154793 16075 authenticator.cpp:326] Authentication requires more steps
3: I1227 23:16:00.154899 16075 authenticatee.cpp:259] Received SASL authentication step
3: I1227 23:16:00.155005 16075 authenticator.cpp:232] Received SASL authentication step
3: I1227 23:16:00.155036 16075 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '482dff07df51' server FQDN: '482dff07df51' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1227 23:16:00.155043 16075 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1227 23:16:00.155069 16075 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1227 23:16:00.155086 16075 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '482dff07df51' server FQDN: '482dff07df51' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1227 23:16:00.155092 16075 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1227 23:16:00.155097 16075 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1227 23:16:00.155109 16075 authenticator.cpp:318] Authentication success
3: I1227 23:16:00.155196 16068 authenticatee.cpp:299] Authentication success
3: I1227 23:16:00.155297 16069 master.cpp:8958] Successfully authenticated principal 'test-principal' at slave(95)@172.17.0.4:46256
3: I1227 23:16:00.155351 16072 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(199)@172.17.0.4:46256
3: I1227 23:16:00.155556 16067 slave.cpp:1229] Successfully authenticated with master master@172.17.0.4:46256
3: I1227 23:16:00.155750 16067 slave.cpp:1709] Will retry registration in 5.587632ms if necessary
3: I1227 23:16:00.155877 16078 master.cpp:6078] Received register agent message from slave(95)@172.17.0.4:46256 (maintenance-host)
3: I1227 23:16:00.155915 16078 master.cpp:3867] Authorizing agent with principal 'test-principal'
3: I1227 23:16:00.156334 16061 master.cpp:6148] Authorized registration of agent at slave(95)@172.17.0.4:46256 (maintenance-host)
3: I1227 23:16:00.156476 16061 master.cpp:6241] Registering agent at slave(95)@172.17.0.4:46256 (maintenance-host) with id 5f361091-9f0f-440a-88af-4db89590a987-S1
3: I1227 23:16:00.156954 16081 registrar.cpp:495] Applied 1 operations in 97111ns; attempting to update the registry
3: I1227 23:16:00.157493 16081 registrar.cpp:552] Successfully updated the registry in 487168ns
3: I1227 23:16:00.157680 16063 master.cpp:6289] Admitted agent 5f361091-9f0f-440a-88af-4db89590a987-S1 at slave(95)@172.17.0.4:46256 (maintenance-host)
3: I1227 23:16:00.158344 16063 master.cpp:6338] Registered agent 5f361091-9f0f-440a-88af-4db89590a987-S1 at slave(95)@172.17.0.4:46256 (maintenance-host) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1227 23:16:00.158674 16084 slave.cpp:1275] Registered with master master@172.17.0.4:46256; given agent ID 5f361091-9f0f-440a-88af-4db89590a987-S1
3: I1227 23:16:00.158784 16076 hierarchical.cpp:574] Added agent 5f361091-9f0f-440a-88af-4db89590a987-S1 (maintenance-host) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
3: I1227 23:16:00.158816 16075 task_status_update_manager.cpp:188] Resuming sending task status updates
3: I1227 23:16:00.158962 16084 slave.cpp:1295] Checkpointing SlaveInfo to '/tmp/MasterMaintenanceTest_EnterMaintenanceMode_3a8PDP/meta/slaves/5f361091-9f0f-440a-88af-4db89590a987-S1/slave.info'
3: I1227 23:16:00.159256 16060 sched.cpp:2009] Asked to stop the driver
3: I1227 23:16:00.159369 16084 slave.cpp:1352] Forwarding agent update {"operations":{},"resource_version_uuid":{"value":"2U7ffCW5R9aoP08J6iHpJA=="},"slave_id":{"value":"5f361091-9f0f-440a-88af-4db89590a987-S1"},"update_oversubscribed_resources":true}
3: I1227 23:16:00.159456 16066 sched.cpp:1191] Stopping framework 5f361091-9f0f-440a-88af-4db89590a987-0000
3: I1227 23:16:00.159668 16080 master.cpp:7268] Received update of agent 5f361091-9f0f-440a-88af-4db89590a987-S1 at slave(95)@172.17.0.4:46256 (maintenance-host) with total oversubscribed resources {}
3: I1227 23:16:00.160102 16080 master.cpp:7362] Ignoring update on agent 5f361091-9f0f-440a-88af-4db89590a987-S1 at slave(95)@172.17.0.4:46256 (maintenance-host) as it reports no changes
3: I1227 23:16:00.160353 16080 master.cpp:9438] Processing TEARDOWN call for framework 5f361091-9f0f-440a-88af-4db89590a987-0000 (default) at scheduler-f883a1a5-d074-42e6-802f-0ab1280484d4@172.17.0.4:46256
3: I1227 23:16:00.160395 16080 master.cpp:9450] Removing framework 5f361091-9f0f-440a-88af-4db89590a987-0000 (default) at scheduler-f883a1a5-d074-42e6-802f-0ab1280484d4@172.17.0.4:46256
3: I1227 23:16:00.160429 16080 master.cpp:3328] Deactivating framework 5f361091-9f0f-440a-88af-4db89590a987-0000 (default) at scheduler-f883a1a5-d074-42e6-802f-0ab1280484d4@172.17.0.4:46256
3: I1227 23:16:00.160526 16076 hierarchical.cpp:1517] Performed allocation for 1 agents in 1.572595ms
3: I1227 23:16:00.160594 16065 slave.cpp:3407] Asked to shut down framework 5f361091-9f0f-440a-88af-4db89590a987-0000 by master@172.17.0.4:46256
3: I1227 23:16:00.160645 16065 slave.cpp:3422] Cannot shut down unknown framework 5f361091-9f0f-440a-88af-4db89590a987-0000
3: I1227 23:16:00.160697 16076 hierarchical.cpp:405] Deactivated framework 5f361091-9f0f-440a-88af-4db89590a987-0000
3: W1227 23:16:00.160941 16080 master.cpp:8587] Master returning resources offered to framework 5f361091-9f0f-440a-88af-4db89590a987-0000 because the framework has terminated or is inactive
3: I1227 23:16:00.161113 16081 slave.cpp:931] Agent terminating
3: I1227 23:16:00.161278 16077 master.cpp:1305] Agent 5f361091-9f0f-440a-88af-4db89590a987-S1 at slave(95)@172.17.0.4:46256 (maintenance-host) disconnected
3: I1227 23:16:00.161298 16077 master.cpp:3365] Disconnecting agent 5f361091-9f0f-440a-88af-4db89590a987-S1 at slave(95)@172.17.0.4:46256 (maintenance-host)
3: I1227 23:16:00.161341 16077 master.cpp:3384] Deactivating agent 5f361091-9f0f-440a-88af-4db89590a987-S1 at slave(95)@172.17.0.4:46256 (maintenance-host)
3: I1227 23:16:00.161866 16073 hierarchical.cpp:344] Removed framework 5f361091-9f0f-440a-88af-4db89590a987-0000
3: I1227 23:16:00.162081 16073 hierarchical.cpp:1192] Recovered cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent 5f361091-9f0f-440a-88af-4db89590a987-S1 from framework 5f361091-9f0f-440a-88af-4db89590a987-0000
3: I1227 23:16:00.162168 16073 hierarchical.cpp:766] Agent 5f361091-9f0f-440a-88af-4db89590a987-S1 deactivated
3: I1227 23:16:00.168154 16060 master.cpp:1147] Master terminating
3: I1227 23:16:00.168869 16075 hierarchical.cpp:609] Removed agent 5f361091-9f0f-440a-88af-4db89590a987-S1
3: [ OK ] MasterMaintenanceTest.EnterMaintenanceMode (153 ms)
3: [ RUN ] MasterMaintenanceTest.BringDownMachines
3: I1227 23:16:00.176301 16060 cluster.cpp:172] Creating default 'local' authorizer
3: I1227 23:16:00.179133 16073 master.cpp:456] Master e93a2d38-9ac9-463f-9b7f-5b67cd86157e (482dff07df51) started on 172.17.0.4:46256
3: I1227 23:16:00.179167 16073 master.cpp:458] Flags at startup: --acls="start_maintenances {
3: principals {
3: values: "test-principal-2"
3: }
3: machines {
3: type: NONE
3: }
3: }
3: " --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/hOcKjC/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/hOcKjC/master" --zk_session_timeout="10secs"
3: I1227 23:16:00.179913 16073 master.cpp:507] Master only allowing authenticated frameworks to register
3: I1227 23:16:00.179927 16073 master.cpp:513] Master only allowing authenticated agents to register
3: I1227 23:16:00.179935 16073 master.cpp:519] Master only allowing authenticated HTTP frameworks to register
3: I1227 23:16:00.179944 16073 credentials.hpp:37] Loading credentials for authentication from '/tmp/hOcKjC/credentials'
3: I1227 23:16:00.180397 16073 master.cpp:563] Using default 'crammd5' authenticator
3: I1227 23:16:00.180673 16073 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1227 23:16:00.180949 16073 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1227 23:16:00.181095 16073 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1227 23:16:00.181243 16073 master.cpp:642] Authorization enabled
3: I1227 23:16:00.181416 16063 whitelist_watcher.cpp:77] No whitelist given
3: I1227 23:16:00.181447 16079 hierarchical.cpp:175] Initialized hierarchical allocator process
3: I1227 23:16:00.184861 16068 master.cpp:2210] Elected as the leading master!
3: I1227 23:16:00.184885 16068 master.cpp:1689] Recovering from registrar
3: I1227 23:16:00.185047 16062 registrar.cpp:347] Recovering registrar
3: I1227 23:16:00.185770 16062 registrar.cpp:391] Successfully fetched the registry (0B) in 690944ns
3: I1227 23:16:00.185890 16062 registrar.cpp:495] Applied 1 operations in 38117ns; attempting to update the registry
3: I1227 23:16:00.186408 16084 registrar.cpp:552] Successfully updated the registry in 458240ns
3: I1227 23:16:00.186549 16084 registrar.cpp:424] Successfully recovered registrar
3: I1227 23:16:00.186945 16061 master.cpp:1803] Recovered 0 agents from the registry (135B); allowing 10mins for agents to re-register
3: I1227 23:16:00.186971 16071 hierarchical.cpp:213] Skipping recovery of hierarchical allocator: nothing to recover
3: I1227 23:16:00.189308 16073 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/machine/down'
3: I1227 23:16:00.191484 16075 http.cpp:1185] HTTP POST for /master/machine/down from 172.17.0.4:47118
3: I1227 23:16:00.195647 16079 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/machine/down'
3: I1227 23:16:00.197132 16077 http.cpp:1185] HTTP POST for /master/machine/down from 172.17.0.4:47120
3: I1227 23:16:00.200959 16084 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/machine/down'
3: I1227 23:16:00.202227 16073 http.cpp:1185] HTTP POST for /master/machine/down from 172.17.0.4:47122
3: I1227 23:16:00.206845 16065 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/maintenance/schedule'
3: I1227 23:16:00.208180 16082 http.cpp:1185] HTTP POST for /master/maintenance/schedule from 172.17.0.4:47124
3: I1227 23:16:00.209430 16084 registrar.cpp:495] Applied 1 operations in 162285ns; attempting to update the registry
3: I1227 23:16:00.210034 16084 registrar.cpp:552] Successfully updated the registry in 539136ns
3: I1227 23:16:00.213528 16081 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/machine/down'
3: I1227 23:16:00.214879 16068 http.cpp:1185] HTTP POST for /master/machine/down from 172.17.0.4:47126
3: I1227 23:16:00.218988 16069 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/machine/down'
3: I1227 23:16:00.220281 16065 http.cpp:1185] HTTP POST for /master/machine/down from 172.17.0.4:47128
3: I1227 23:16:00.221078 16068 registrar.cpp:495] Applied 1 operations in 80504ns; attempting to update the registry
3: I1227 23:16:00.221542 16068 registrar.cpp:552] Successfully updated the registry in 413184ns
3: I1227 23:16:00.224650 16070 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/machine/down'
3: I1227 23:16:00.225970 16064 http.cpp:1185] HTTP POST for /master/machine/down from 172.17.0.4:47130
3: I1227 23:16:00.229645 16080 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/machine/down'
3: I1227 23:16:00.231027 16061 http.cpp:1185] HTTP POST for /master/machine/down from 172.17.0.4:47132
3: I1227 23:16:00.234459 16066 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/machine/down'
3: I1227 23:16:00.235766 16075 http.cpp:1185] HTTP POST for /master/machine/down from 172.17.0.4:47134
3: I1227 23:16:00.236558 16081 registrar.cpp:495] Applied 1 operations in 69817ns; attempting to update the registry
3: I1227 23:16:00.237038 16081 registrar.cpp:552] Successfully updated the registry in 430080ns
3: I1227 23:16:00.238505 16060 master.cpp:1147] Master terminating
3: [ OK ] MasterMaintenanceTest.BringDownMachines (68 ms)
3: [ RUN ] MasterMaintenanceTest.BringUpMachines
3: I1227 23:16:00.245046 16060 cluster.cpp:172] Creating default 'local' authorizer
3: I1227 23:16:00.248551 16065 master.cpp:456] Master d2cfa5a4-601f-4db3-bbba-3e3207038ca3 (482dff07df51) started on 172.17.0.4:46256
3: I1227 23:16:00.248589 16065 master.cpp:458] Flags at startup: --acls="stop_maintenances {
3: principals {
3: values: "test-principal-2"
3: }
3: machines {
3: type: NONE
3: }
3: }
3: " --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/ltsLc4/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/ltsLc4/master" --zk_session_timeout="10secs"
3: I1227 23:16:00.248981 16065 master.cpp:507] Master only allowing authenticated frameworks to register
3: I1227 23:16:00.248988 16065 master.cpp:513] Master only allowing authenticated agents to register
3: I1227 23:16:00.248992 16065 master.cpp:519] Master only allowing authenticated HTTP frameworks to register
3: I1227 23:16:00.248997 16065 credentials.hpp:37] Loading credentials for authentication from '/tmp/ltsLc4/credentials'
3: I1227 23:16:00.249249 16065 master.cpp:563] Using default 'crammd5' authenticator
3: I1227 23:16:00.249393 16065 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1227 23:16:00.249533 16065 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1227 23:16:00.249651 16065 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1227 23:16:00.249763 16065 master.cpp:642] Authorization enabled
3: I1227 23:16:00.249891 16083 hierarchical.cpp:175] Initialized hierarchical allocator process
3: I1227 23:16:00.249969 16067 whitelist_watcher.cpp:77] No whitelist given
3: I1227 23:16:00.252630 16062 master.cpp:2210] Elected as the leading master!
3: I1227 23:16:00.252661 16062 master.cpp:1689] Recovering from registrar
3: I1227 23:16:00.252871 16072 registrar.cpp:347] Recovering registrar
3: I1227 23:16:00.253459 16072 registrar.cpp:391] Successfully fetched the registry (0B) in 556032ns
3: I1227 23:16:00.253566 16072 registrar.cpp:495] Applied 1 operations in 34077ns; attempting to update the registry
3: I1227 23:16:00.254128 16072 registrar.cpp:552] Successfully updated the registry in 511232ns
3: I1227 23:16:00.254256 16072 registrar.cpp:424] Successfully recovered registrar
3: I1227 23:16:00.254768 16061 master.cpp:1803] Recovered 0 agents from the registry (135B); allowing 10mins for agents to re-register
3: I1227 23:16:00.254796 16078 hierarchical.cpp:213] Skipping recovery of hierarchical allocator: nothing to recover
3: I1227 23:16:00.257133 16065 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/machine/up'
3: I1227 23:16:00.258517 16069 http.cpp:1185] HTTP POST for /master/machine/up from 172.17.0.4:47136
3: I1227 23:16:00.262064 16079 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/maintenance/schedule'
3: I1227 23:16:00.263309 16062 http.cpp:1185] HTTP POST for /master/maintenance/schedule from 172.17.0.4:47138
3: I1227 23:16:00.264434 16066 registrar.cpp:495] Applied 1 operations in 134873ns; attempting to update the registry
3: I1227 23:16:00.264997 16066 registrar.cpp:552] Successfully updated the registry in 505856ns
3: I1227 23:16:00.268227 16079 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/machine/up'
3: I1227 23:16:00.269431 16082 http.cpp:1185] HTTP POST for /master/machine/up from 172.17.0.4:47140
3: I1227 23:16:00.272579 16070 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/machine/down'
3: I1227 23:16:00.274328 16065 http.cpp:1185] HTTP POST for /master/machine/down from 172.17.0.4:47142
3: I1227 23:16:00.275159 16063 registrar.cpp:495] Applied 1 operations in 89777ns; attempting to update the registry
3: I1227 23:16:00.275668 16063 registrar.cpp:552] Successfully updated the registry in 453120ns
3: I1227 23:16:00.278439 16067 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/machine/up'
3: I1227 23:16:00.279659 16075 http.cpp:1185] HTTP POST for /master/machine/up from 172.17.0.4:47144
3: I1227 23:16:00.282675 16078 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/machine/up'
3: I1227 23:16:00.283893 16065 http.cpp:1185] HTTP POST for /master/machine/up from 172.17.0.4:47146
3: I1227 23:16:00.284695 16082 registrar.cpp:495] Applied 1 operations in 123013ns; attempting to update the registry
3: I1227 23:16:00.285192 16082 registrar.cpp:552] Successfully updated the registry in 444160ns
3: I1227 23:16:00.287889 16070 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/maintenance/schedule'
3: I1227 23:16:00.289213 16069 http.cpp:1185] HTTP GET for /master/maintenance/schedule from 172.17.0.4:47148
3: I1227 23:16:00.292464 16080 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/machine/down'
3: I1227 23:16:00.293992 16079 http.cpp:1185] HTTP POST for /master/machine/down from 172.17.0.4:47150
3: I1227 23:16:00.294759 16084 registrar.cpp:495] Applied 1 operations in 83531ns; attempting to update the registry
3: I1227 23:16:00.295217 16084 registrar.cpp:552] Successfully updated the registry in 409088ns
3: I1227 23:16:00.297904 16062 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/machine/up'
3: I1227 23:16:00.299118 16082 http.cpp:1185] HTTP POST for /master/machine/up from 172.17.0.4:47152
3: I1227 23:16:00.299901 16077 registrar.cpp:495] Applied 1 operations in 93947ns; attempting to update the registry
3: I1227 23:16:00.300349 16077 registrar.cpp:552] Successfully updated the registry in 398080ns
3: I1227 23:16:00.303396 16062 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/maintenance/schedule'
3: I1227 23:16:00.304541 16081 http.cpp:1185] HTTP GET for /master/maintenance/schedule from 172.17.0.4:47154
3: I1227 23:16:00.306207 16060 master.cpp:1147] Master terminating
3: [ OK ] MasterMaintenanceTest.BringUpMachines (68 ms)
3: [ RUN ] MasterMaintenanceTest.MachineStatus
3: I1227 23:16:00.312587 16060 cluster.cpp:172] Creating default 'local' authorizer
3: I1227 23:16:00.315475 16081 master.cpp:456] Master 4a452fab-2fc5-4a42-bb8e-bf319cce789e (482dff07df51) started on 172.17.0.4:46256
3: I1227 23:16:00.315510 16081 master.cpp:458] Flags at startup: --acls="get_maintenance_statuses {
3: principals {
3: values: "test-principal-2"
3: }
3: machines {
3: type: NONE
3: }
3: }
3: " --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/dN7Pfw/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/dN7Pfw/master" --zk_session_timeout="10secs"
3: I1227 23:16:00.315868 16081 master.cpp:507] Master only allowing authenticated frameworks to register
3: I1227 23:16:00.315876 16081 master.cpp:513] Master only allowing authenticated agents to register
3: I1227 23:16:00.315881 16081 master.cpp:519] Master only allowing authenticated HTTP frameworks to register
3: I1227 23:16:00.315886 16081 credentials.hpp:37] Loading credentials for authentication from '/tmp/dN7Pfw/credentials'
3: I1227 23:16:00.316167 16081 master.cpp:563] Using default 'crammd5' authenticator
3: I1227 23:16:00.316321 16081 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1227 23:16:00.316496 16081 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1227 23:16:00.316651 16081 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1227 23:16:00.316776 16081 master.cpp:642] Authorization enabled
3: I1227 23:16:00.316947 16069 hierarchical.cpp:175] Initialized hierarchical allocator process
3: I1227 23:16:00.316977 16071 whitelist_watcher.cpp:77] No whitelist given
3: I1227 23:16:00.319978 16080 master.cpp:2210] Elected as the leading master!
3: I1227 23:16:00.319996 16080 master.cpp:1689] Recovering from registrar
3: I1227 23:16:00.320118 16070 registrar.cpp:347] Recovering registrar
3: I1227 23:16:00.320746 16070 registrar.cpp:391] Successfully fetched the registry (0B) in 601088ns
3: I1227 23:16:00.320858 16070 registrar.cpp:495] Applied 1 operations in 40835ns; attempting to update the registry
3: I1227 23:16:00.321374 16070 registrar.cpp:552] Successfully updated the registry in 465920ns
3: I1227 23:16:00.321491 16070 registrar.cpp:424] Successfully recovered registrar
3: I1227 23:16:00.321851 16064 master.cpp:1803] Recovered 0 agents from the registry (135B); allowing 10mins for agents to re-register
3: I1227 23:16:00.321920 16061 hierarchical.cpp:213] Skipping recovery of hierarchical allocator: nothing to recover
3: I1227 23:16:00.324234 16067 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/maintenance/schedule'
3: I1227 23:16:00.325747 16068 http.cpp:1185] HTTP POST for /master/maintenance/schedule from 172.17.0.4:47156
3: I1227 23:16:00.327039 16066 registrar.cpp:495] Applied 1 operations in 125224ns; attempting to update the registry
3: I1227 23:16:00.327514 16066 registrar.cpp:552] Successfully updated the registry in 421120ns
3: I1227 23:16:00.330498 16082 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/maintenance/status'
3: I1227 23:16:00.331723 16084 http.cpp:1185] HTTP GET for /master/maintenance/status from 172.17.0.4:47158
3: I1227 23:16:00.336714 16078 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/maintenance/status'
3: I1227 23:16:00.338156 16082 http.cpp:1185] HTTP GET for /master/maintenance/status from 172.17.0.4:47164
3: I1227 23:16:00.342697 16069 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/machine/down'
3: I1227 23:16:00.343940 16067 http.cpp:1185] HTTP POST for /master/machine/down from 172.17.0.4:47166
3: I1227 23:16:00.344897 16062 registrar.cpp:495] Applied 1 operations in 121186ns; attempting to update the registry
3: I1227 23:16:00.345580 16062 registrar.cpp:552] Successfully updated the registry in 611072ns
3: I1227 23:16:00.348474 16069 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/maintenance/status'
3: I1227 23:16:00.349757 16077 http.cpp:1185] HTTP GET for /master/maintenance/status from 172.17.0.4:47168
3: I1227 23:16:00.353775 16079 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/machine/up'
3: I1227 23:16:00.354991 16067 http.cpp:1185] HTTP POST for /master/machine/up from 172.17.0.4:47174
3: I1227 23:16:00.355881 16068 registrar.cpp:495] Applied 1 operations in 125866ns; attempting to update the registry
3: I1227 23:16:00.356755 16068 registrar.cpp:552] Successfully updated the registry in 775936ns
3: I1227 23:16:00.359823 16069 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/maintenance/status'
3: I1227 23:16:00.361053 16082 http.cpp:1185] HTTP GET for /master/maintenance/status from 172.17.0.4:47176
3: I1227 23:16:00.363617 16060 master.cpp:1147] Master terminating
3: [ OK ] MasterMaintenanceTest.MachineStatus (57 ms)
3: [ RUN ] MasterMaintenanceTest.InverseOffers
3: I1227 23:16:00.370069 16060 cluster.cpp:172] Creating default 'local' authorizer
3: I1227 23:16:00.372859 16082 master.cpp:456] Master d4802668-e541-4c9e-8864-1bc5ce1d7f07 (482dff07df51) started on 172.17.0.4:46256
3: I1227 23:16:00.372889 16082 master.cpp:458] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/3yUtrY/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/3yUtrY/master" --zk_session_timeout="10secs"
3: I1227 23:16:00.373165 16082 master.cpp:507] Master only allowing authenticated frameworks to register
3: I1227 23:16:00.373173 16082 master.cpp:513] Master only allowing authenticated agents to register
3: I1227 23:16:00.373178 16082 master.cpp:519] Master only allowing authenticated HTTP frameworks to register
3: I1227 23:16:00.373183 16082 credentials.hpp:37] Loading credentials for authentication from '/tmp/3yUtrY/credentials'
3: I1227 23:16:00.373421 16082 master.cpp:563] Using default 'crammd5' authenticator
3: I1227 23:16:00.373564 16082 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
Process leaked file descriptors. See https://jenkins.io/redirect/troubleshooting/process-leaked-file-descriptors for more information
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user songzihao1990@gmail.com
Not sending mail to unregistered user yujie.jay@gmail.com
Jenkins build is back to normal : Mesos-Buildbot » cmake,clang,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4676
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4676/display/redirect?page=changes>