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/02/02 01:09:45 UTC

Build failed in Jenkins: Mesos-Reviewbot #16947

See <https://builds.apache.org/job/Mesos-Reviewbot/16947/changes>

Changes:

[alexr] Avoided shadowing in `Slave::run()`.

[alexr] Restored quota correctly during allocator recovery.

[alexr] Added MESOS-7008 to CHANGELOG for 1.0.3 and 1.1.1.

[alexr] Fixed a typo in "mesos.proto".

------------------------------------------
[...truncated 70507 lines...]
I0202 01:09:13.194790 30193 slave.cpp:3045] Status update manager successfully handled status update acknowledgement (UUID: e4bb49ef-0f9a-4a6a-a6b4-13b17a8f744b) for task 1 of framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000
I0202 01:09:13.199789 30205 exec.cpp:391] Executor received framework message
I0202 01:09:13.199919 30205 exec.cpp:400] Executor::frameworkMessage took 77656ns
I0202 01:09:13.203869 30202 slave.cpp:4264] Sending message for framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000 to scheduler-fc282ebd-a776-4a43-998e-d040ff3aa4ef@172.17.0.3:57175
I0202 01:09:13.204180 30193 sched.cpp:1177] Scheduler::frameworkMessage took 81315ns
I0202 01:09:13.204731 30173 sched.cpp:2021] Asked to stop the driver
I0202 01:09:13.204952 30192 sched.cpp:1203] Stopping framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000
I0202 01:09:13.205418 30192 master.cpp:7560] Processing TEARDOWN call for framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000 (default) at scheduler-fc282ebd-a776-4a43-998e-d040ff3aa4ef@172.17.0.3:57175
I0202 01:09:13.205453 30192 master.cpp:7572] Removing framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000 (default) at scheduler-fc282ebd-a776-4a43-998e-d040ff3aa4ef@172.17.0.3:57175
I0202 01:09:13.205472 30192 master.cpp:3080] Deactivating framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000 (default) at scheduler-fc282ebd-a776-4a43-998e-d040ff3aa4ef@172.17.0.3:57175
I0202 01:09:13.205759 30192 master.cpp:8157] Updating the state of task 1 of framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0202 01:09:13.206679 30192 master.cpp:8251] Removing task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000 on agent 869badf0-0fc0-418e-99b3-dbfa8c22e104-S0 at slave(179)@172.17.0.3:57175 (85172c83c580)
I0202 01:09:13.207113 30192 master.cpp:8280] Removing executor 'default' with resources {} of framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000 on agent 869badf0-0fc0-418e-99b3-dbfa8c22e104-S0 at slave(179)@172.17.0.3:57175 (85172c83c580)
I0202 01:09:13.207453 30200 hierarchical.cpp:387] Deactivated framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000
I0202 01:09:13.207465 30206 slave.cpp:2598] Asked to shut down framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000 by master@172.17.0.3:57175
I0202 01:09:13.207516 30206 slave.cpp:2623] Shutting down framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000
I0202 01:09:13.207583 30206 slave.cpp:5005] Shutting down executor 'default' of framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000 at executor(74)@172.17.0.3:57175
I0202 01:09:13.207725 30207 exec.cpp:410] Executor asked to shutdown
I0202 01:09:13.207785 30207 exec.cpp:425] Executor::shutdown took 22205ns
I0202 01:09:13.208079 30207 slave.cpp:4328] Got exited event for executor(74)@172.17.0.3:57175
I0202 01:09:13.208562 30200 hierarchical.cpp:1013] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 869badf0-0fc0-418e-99b3-dbfa8c22e104-S0 from framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000
I0202 01:09:13.208832 30200 hierarchical.cpp:338] Removed framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000
I0202 01:09:13.226263 30197 slave.cpp:4691] Executor 'default' of framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000 exited with status 0
I0202 01:09:13.226410 30197 slave.cpp:4791] Cleaning up executor 'default' of framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000 at executor(74)@172.17.0.3:57175
I0202 01:09:13.226852 30197 slave.cpp:4879] Cleaning up framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000
W0202 01:09:13.227663 30202 master.cpp:6145] Ignoring unknown exited executor 'default' of framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000 on agent 869badf0-0fc0-418e-99b3-dbfa8c22e104-S0 at slave(179)@172.17.0.3:57175 (85172c83c580)
I0202 01:09:13.227670 30206 status_update_manager.cpp:285] Closing status update streams for framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000
I0202 01:09:13.227769 30206 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 869badf0-0fc0-418e-99b3-dbfa8c22e104-0000
I0202 01:09:13.227893 30205 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_6urauj/slaves/869badf0-0fc0-418e-99b3-dbfa8c22e104-S0/frameworks/869badf0-0fc0-418e-99b3-dbfa8c22e104-0000/executors/default/runs/50cdf088-8952-482c-9338-75af5bb87cd2' for gc 6.99999737713185days in the future
I0202 01:09:13.228340 30173 slave.cpp:801] Agent terminating
I0202 01:09:13.228374 30205 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_6urauj/slaves/869badf0-0fc0-418e-99b3-dbfa8c22e104-S0/frameworks/869badf0-0fc0-418e-99b3-dbfa8c22e104-0000/executors/default' for gc 6.99999737509037days in the future
I0202 01:09:13.228526 30205 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_6urauj/slaves/869badf0-0fc0-418e-99b3-dbfa8c22e104-S0/frameworks/869badf0-0fc0-418e-99b3-dbfa8c22e104-0000' for gc 6.99999737302222days in the future
I0202 01:09:13.231427 30202 master.cpp:1263] Agent 869badf0-0fc0-418e-99b3-dbfa8c22e104-S0 at slave(179)@172.17.0.3:57175 (85172c83c580) disconnected
I0202 01:09:13.231483 30202 master.cpp:3117] Disconnecting agent 869badf0-0fc0-418e-99b3-dbfa8c22e104-S0 at slave(179)@172.17.0.3:57175 (85172c83c580)
I0202 01:09:13.231565 30202 master.cpp:3136] Deactivating agent 869badf0-0fc0-418e-99b3-dbfa8c22e104-S0 at slave(179)@172.17.0.3:57175 (85172c83c580)
I0202 01:09:13.231706 30197 hierarchical.cpp:579] Agent 869badf0-0fc0-418e-99b3-dbfa8c22e104-S0 deactivated
I0202 01:09:13.234956 30173 master.cpp:1102] Master terminating
I0202 01:09:13.236153 30207 hierarchical.cpp:512] Removed agent 869badf0-0fc0-418e-99b3-dbfa8c22e104-S0
[       OK ] MasterTest.FrameworkMessage (171 ms)
[ RUN      ] MasterTest.MultipleExecutors
I0202 01:09:13.262303 30173 cluster.cpp:160] Creating default 'local' authorizer
I0202 01:09:13.266393 30204 master.cpp:383] Master 0616b8f0-f9ac-46f6-b370-f315fe93c428 (85172c83c580) started on 172.17.0.3:57175
I0202 01:09:13.266602 30204 master.cpp:385] 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/RbJSMF/credentials" --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" --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="/mesos/mesos-1.2.0/_inst/share/mesos/webui" --work_dir="/tmp/RbJSMF/master" --zk_session_timeout="10secs"
I0202 01:09:13.267328 30204 master.cpp:435] Master only allowing authenticated frameworks to register
I0202 01:09:13.267485 30204 master.cpp:449] Master only allowing authenticated agents to register
I0202 01:09:13.267627 30204 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0202 01:09:13.267783 30204 credentials.hpp:37] Loading credentials for authentication from '/tmp/RbJSMF/credentials'
I0202 01:09:13.268295 30204 master.cpp:507] Using default 'crammd5' authenticator
I0202 01:09:13.268625 30204 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0202 01:09:13.268966 30204 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0202 01:09:13.269361 30204 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0202 01:09:13.269706 30204 master.cpp:587] Authorization enabled
I0202 01:09:13.271340 30194 whitelist_watcher.cpp:77] No whitelist given
I0202 01:09:13.271488 30199 hierarchical.cpp:152] Initialized hierarchical allocator process
I0202 01:09:13.274109 30204 master.cpp:2123] Elected as the leading master!
I0202 01:09:13.276325 30204 master.cpp:1645] Recovering from registrar
I0202 01:09:13.276844 30204 registrar.cpp:329] Recovering registrar
I0202 01:09:13.278606 30204 registrar.cpp:362] Successfully fetched the registry (0B) in 1.579008ms
I0202 01:09:13.278950 30204 registrar.cpp:461] Applied 1 operations in 29563ns; attempting to update the registry
I0202 01:09:13.280830 30207 registrar.cpp:506] Successfully updated the registry in 1.682176ms
I0202 01:09:13.281080 30207 registrar.cpp:392] Successfully recovered registrar
I0202 01:09:13.281611 30207 master.cpp:1761] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0202 01:09:13.281689 30200 hierarchical.cpp:179] Skipping recovery of hierarchical allocator: nothing to recover
I0202 01:09:13.289885 30173 cluster.cpp:446] Creating default 'local' authorizer
I0202 01:09:13.293849 30173 sched.cpp:232] Version: 1.2.0
I0202 01:09:13.295364 30195 slave.cpp:209] Mesos agent started on (180)@172.17.0.3:57175
I0202 01:09:13.296080 30194 sched.cpp:336] New master detected at master@172.17.0.3:57175
I0202 01:09:13.296246 30194 sched.cpp:407] Authenticating with master master@172.17.0.3:57175
I0202 01:09:13.295413 30195 slave.cpp:210] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/MasterTest_MultipleExecutors_ZG6lyR/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_ZG6lyR/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_command_executor="false" --http_credentials="/tmp/MasterTest_MultipleExecutors_ZG6lyR/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.2.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/MasterTest_MultipleExecutors_ZG6lyR" --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/MasterTest_MultipleExecutors_S3sBFL"
I0202 01:09:13.296273 30194 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0202 01:09:13.296308 30195 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_ZG6lyR/credential'
I0202 01:09:13.296586 30195 slave.cpp:352] Agent using credential for: test-principal
I0202 01:09:13.296615 30195 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_ZG6lyR/http_credentials'
I0202 01:09:13.296723 30194 authenticatee.cpp:121] Creating new client SASL connection
I0202 01:09:13.296946 30195 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0202 01:09:13.297112 30195 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0202 01:09:13.297188 30194 master.cpp:7018] Authenticating scheduler-c330f47f-774d-4ae7-bebe-59e0adaeec1f@172.17.0.3:57175
I0202 01:09:13.297590 30196 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(416)@172.17.0.3:57175
I0202 01:09:13.297981 30196 authenticator.cpp:98] Creating new server SASL connection
I0202 01:09:13.298238 30196 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0202 01:09:13.298274 30196 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0202 01:09:13.298377 30196 authenticator.cpp:204] Received SASL authentication start
I0202 01:09:13.298451 30196 authenticator.cpp:326] Authentication requires more steps
I0202 01:09:13.298547 30196 authenticatee.cpp:259] Received SASL authentication step
I0202 01:09:13.298660 30196 authenticator.cpp:232] Received SASL authentication step
I0202 01:09:13.298698 30196 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '85172c83c580' server FQDN: '85172c83c580' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0202 01:09:13.298722 30196 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0202 01:09:13.298773 30196 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0202 01:09:13.298804 30196 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '85172c83c580' server FQDN: '85172c83c580' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0202 01:09:13.298826 30196 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0202 01:09:13.298840 30196 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0202 01:09:13.298859 30196 authenticator.cpp:318] Authentication success
I0202 01:09:13.299046 30196 authenticatee.cpp:299] Authentication success
I0202 01:09:13.299149 30196 master.cpp:7048] Successfully authenticated principal 'test-principal' at scheduler-c330f47f-774d-4ae7-bebe-59e0adaeec1f@172.17.0.3:57175
I0202 01:09:13.299093 30195 slave.cpp:539] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0202 01:09:13.299223 30195 slave.cpp:547] Agent attributes: [  ]
I0202 01:09:13.299243 30195 slave.cpp:552] Agent hostname: 85172c83c580
I0202 01:09:13.299257 30196 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(416)@172.17.0.3:57175
I0202 01:09:13.299621 30199 sched.cpp:513] Successfully authenticated with master master@172.17.0.3:57175
I0202 01:09:13.299880 30199 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.3:57175
I0202 01:09:13.300554 30199 sched.cpp:869] Will retry registration in 1.489071158secs if necessary
I0202 01:09:13.300753 30201 status_update_manager.cpp:177] Pausing sending status updates
I0202 01:09:13.300755 30198 master.cpp:2736] Received SUBSCRIBE call for framework 'default' at scheduler-c330f47f-774d-4ae7-bebe-59e0adaeec1f@172.17.0.3:57175
I0202 01:09:13.300878 30198 master.cpp:2159] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0202 01:09:13.301583 30207 master.cpp:2812] Subscribing framework default with checkpointing disabled and capabilities [  ]
I0202 01:09:13.302592 30199 hierarchical.cpp:272] Added framework 0616b8f0-f9ac-46f6-b370-f315fe93c428-0000
I0202 01:09:13.302714 30207 sched.cpp:759] Framework registered with 0616b8f0-f9ac-46f6-b370-f315fe93c428-0000
I0202 01:09:13.302754 30199 hierarchical.cpp:1697] No allocations performed
I0202 01:09:13.302772 30207 sched.cpp:773] Scheduler::registered took 26998ns
I0202 01:09:13.302803 30199 hierarchical.cpp:1786] No inverse offers to send out!
I0202 01:09:13.302865 30199 hierarchical.cpp:1322] Performed allocation for 0 agents in 166256ns
I0202 01:09:13.303592 30195 state.cpp:60] Recovering state from '/tmp/MasterTest_MultipleExecutors_S3sBFL/meta'
I0202 01:09:13.303973 30193 status_update_manager.cpp:203] Recovering status update manager
I0202 01:09:13.304627 30198 slave.cpp:5422] Finished recovery
I0202 01:09:13.305253 30198 slave.cpp:5596] Querying resource estimator for oversubscribable resources
I0202 01:09:13.305878 30198 slave.cpp:929] New master detected at master@172.17.0.3:57175
I0202 01:09:13.306054 30198 slave.cpp:964] Detecting new master
I0202 01:09:13.306177 30198 slave.cpp:5610] Received oversubscribable resources {} from the resource estimator
I0202 01:09:13.306282 30198 status_update_manager.cpp:177] Pausing sending status updates
I0202 01:09:13.311564 30195 slave.cpp:991] Authenticating with master master@172.17.0.3:57175
I0202 01:09:13.311743 30195 slave.cpp:1002] Using default CRAM-MD5 authenticatee
I0202 01:09:13.312177 30195 authenticatee.cpp:121] Creating new client SASL connection
I0202 01:09:13.312544 30195 master.cpp:7018] Authenticating slave(180)@172.17.0.3:57175
I0202 01:09:13.312770 30195 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(417)@172.17.0.3:57175
I0202 01:09:13.313195 30195 authenticator.cpp:98] Creating new server SASL connection
I0202 01:09:13.313448 30195 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0202 01:09:13.313484 30195 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0202 01:09:13.313591 30195 authenticator.cpp:204] Received SASL authentication start
I0202 01:09:13.313664 30195 authenticator.cpp:326] Authentication requires more steps
I0202 01:09:13.313762 30195 authenticatee.cpp:259] Received SASL authentication step
I0202 01:09:13.313872 30195 authenticator.cpp:232] Received SASL authentication step
I0202 01:09:13.313908 30195 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '85172c83c580' server FQDN: '85172c83c580' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0202 01:09:13.313931 30195 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0202 01:09:13.313977 30195 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0202 01:09:13.314004 30195 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '85172c83c580' server FQDN: '85172c83c580' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0202 01:09:13.314016 30195 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0202 01:09:13.314025 30195 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0202 01:09:13.314043 30195 authenticator.cpp:318] Authentication success
I0202 01:09:13.314208 30195 authenticatee.cpp:299] Authentication success
I0202 01:09:13.314303 30195 master.cpp:7048] Successfully authenticated principal 'test-principal' at slave(180)@172.17.0.3:57175
I0202 01:09:13.314404 30195 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(417)@172.17.0.3:57175
I0202 01:09:13.314797 30195 slave.cpp:1086] Successfully authenticated with master master@172.17.0.3:57175
I0202 01:09:13.315016 30195 slave.cpp:1508] Will retry registration in 17.60923ms if necessary
I0202 01:09:13.315518 30195 master.cpp:5303] Registering agent at slave(180)@172.17.0.3:57175 (85172c83c580) with id 0616b8f0-f9ac-46f6-b370-f315fe93c428-S0
I0202 01:09:13.316212 30195 registrar.cpp:461] Applied 1 operations in 84725ns; attempting to update the registry
I0202 01:09:13.318691 30195 registrar.cpp:506] Successfully updated the registry in 2.38592ms
I0202 01:09:13.319757 30207 slave.cpp:4286] Received ping from slave-observer(179)@172.17.0.3:57175
I0202 01:09:13.319928 30207 slave.cpp:1132] Registered with master master@172.17.0.3:57175; given agent ID 0616b8f0-f9ac-46f6-b370-f315fe93c428-S0
I0202 01:09:13.319844 30203 master.cpp:5377] Registered agent 0616b8f0-f9ac-46f6-b370-f315fe93c428-S0 at slave(180)@172.17.0.3:57175 (85172c83c580) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0202 01:09:13.319958 30207 fetcher.cpp:90] Clearing fetcher cache
I0202 01:09:13.320507 30200 hierarchical.cpp:479] Added agent 0616b8f0-f9ac-46f6-b370-f315fe93c428-S0 (85172c83c580) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0202 01:09:13.320905 30207 slave.cpp:1160] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_S3sBFL/meta/slaves/0616b8f0-f9ac-46f6-b370-f315fe93c428-S0/slave.info'
I0202 01:09:13.321575 30207 slave.cpp:1198] Forwarding total oversubscribed resources {}
I0202 01:09:13.322011 30207 master.cpp:5876] Received update of agent 0616b8f0-f9ac-46f6-b370-f315fe93c428-S0 at slave(180)@172.17.0.3:57175 (85172c83c580) with total oversubscribed resources {}
I0202 01:09:13.322978 30207 master.cpp:6847] Sending 1 offers to framework 0616b8f0-f9ac-46f6-b370-f315fe93c428-0000 (default) at scheduler-c330f47f-774d-4ae7-bebe-59e0adaeec1f@172.17.0.3:57175
I0202 01:09:13.322123 30200 hierarchical.cpp:1786] No inverse offers to send out!
I0202 01:09:13.321707 30203 status_update_manager.cpp:184] Resuming sending status updates
I0202 01:09:13.323273 30200 hierarchical.cpp:1322] Performed allocation for 1 agents in 2.603895ms
I0202 01:09:13.323655 30200 hierarchical.cpp:550] Agent 0616b8f0-f9ac-46f6-b370-f315fe93c428-S0 (85172c83c580) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000])
I0202 01:09:13.324018 30200 hierarchical.cpp:1697] No allocations performed
I0202 01:09:13.324069 30200 hierarchical.cpp:1786] No inverse offers to send out!
I0202 01:09:13.324121 30200 hierarchical.cpp:1322] Performed allocation for 1 agents in 207704ns
I0202 01:09:13.324540 30207 sched.cpp:933] Scheduler::resourceOffers took 156227ns
I0202 01:09:13.328205 30207 master.cpp:3730] Processing ACCEPT call for offers: [ 0616b8f0-f9ac-46f6-b370-f315fe93c428-O0 ] on agent 0616b8f0-f9ac-46f6-b370-f315fe93c428-S0 at slave(180)@172.17.0.3:57175 (85172c83c580) for framework 0616b8f0-f9ac-46f6-b370-f315fe93c428-0000 (default) at scheduler-c330f47f-774d-4ae7-bebe-59e0adaeec1f@172.17.0.3:57175
I0202 01:09:13.328567 30207 master.cpp:3318] Authorizing framework principal 'test-principal' to launch task 1
I0202 01:09:13.329010 30207 master.cpp:3318] Authorizing framework principal 'test-principal' to launch task 2
W0202 01:09:13.333096 30207 validation.cpp:1048] Executor 'executor-1' 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.
W0202 01:09:13.333353 30207 validation.cpp:1060] Executor 'executor-1' for task '1' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0202 01:09:13.333932 30207 master.cpp:8902] Adding task 1 with resources cpus(*):1; mem(*):512 on agent 0616b8f0-f9ac-46f6-b370-f315fe93c428-S0 at slave(180)@172.17.0.3:57175 (85172c83c580)
I0202 01:09:13.334300 30207 master.cpp:4380] Launching task 1 of framework 0616b8f0-f9ac-46f6-b370-f315fe93c428-0000 (default) at scheduler-c330f47f-774d-4ae7-bebe-59e0adaeec1f@172.17.0.3:57175 with resources cpus(*):1; mem(*):512 on agent 0616b8f0-f9ac-46f6-b370-f315fe93c428-S0 at slave(180)@172.17.0.3:57175 (85172c83c580)
I0202 01:09:13.335670 30206 slave.cpp:1576] Got assigned task '1' for framework 0616b8f0-f9ac-46f6-b370-f315fe93c428-0000
W0202 01:09:13.336189 30207 validation.cpp:1048] Executor 'executor-2' for task '2' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0202 01:09:13.336383 30207 validation.cpp:1060] Executor 'executor-2' for task '2' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0202 01:09:13.336555 30206 slave.cpp:1736] Launching task '1' for framework 0616b8f0-f9ac-46f6-b370-f315fe93c428-0000
I0202 01:09:13.337091 30207 master.cpp:8902] Adding task 2 with resources cpus(*):1; mem(*):512 on agent 0616b8f0-f9ac-46f6-b370-f315fe93c428-S0 at slave(180)@172.17.0.3:57175 (85172c83c580)
I0202 01:09:13.337360 30206 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_S3sBFL/slaves/0616b8f0-f9ac-46f6-b370-f315fe93c428-S0/frameworks/0616b8f0-f9ac-46f6-b370-f315fe93c428-0000/executors/executor-1/runs/f6a8b273-3b0c-49a1-9810-9315c55c39bf' to user 'mesos'
I0202 01:09:13.337582 30207 master.cpp:4380] Launching task 2 of framework 0616b8f0-f9ac-46f6-b370-f315fe93c428-0000 (default) at scheduler-c330f47f-774d-4ae7-bebe-59e0adaeec1f@172.17.0.3:57175 with resources cpus(*):1; mem(*):512 on agent 0616b8f0-f9ac-46f6-b370-f315fe93c428-S0 at slave(180)@172.17.0.3:57175 (85172c83c580)
I0202 01:09:13.339762 30207 hierarchical.cpp:1013] Recovered ports(*):[31000-32000]; disk(*):1024 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024) on agent 0616b8f0-f9ac-46f6-b370-f315fe93c428-S0 from framework 0616b8f0-f9ac-46f6-b370-f315fe93c428-0000
I0202 01:09:13.340035 30207 hierarchical.cpp:1050] Framework 0616b8f0-f9ac-46f6-b370-f315fe93c428-0000 filtered agent 0616b8f0-f9ac-46f6-b370-f315fe93c428-S0 for 5secs
I0202 01:09:13.337662 30206 slave.cpp:6331] Launching executor 'executor-1' of framework 0616b8f0-f9ac-46f6-b370-f315fe93c428-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_S3sBFL/slaves/0616b8f0-f9ac-46f6-b370-f315fe93c428-S0/frameworks/0616b8f0-f9ac-46f6-b370-f315fe93c428-0000/executors/executor-1/runs/f6a8b273-3b0c-49a1-9810-9315c55c39bf'
I0202 01:09:13.344194 30206 slave.cpp:2058] Queued task '1' for executor 'executor-1' of framework 0616b8f0-f9ac-46f6-b370-f315fe93c428-0000
I0202 01:09:13.344539 30206 slave.cpp:1576] Got assigned task '2' for framework 0616b8f0-f9ac-46f6-b370-f315fe93c428-0000
I0202 01:09:13.345197 30206 slave.cpp:882] Successfully attached file '/tmp/MasterTest_MultipleExecutors_S3sBFL/slaves/0616b8f0-f9ac-46f6-b370-f315fe93c428-S0/frameworks/0616b8f0-f9ac-46f6-b370-f315fe93c428-0000/executors/executor-1/runs/f6a8b273-3b0c-49a1-9810-9315c55c39bf'
I0202 01:09:13.345293 30206 slave.cpp:1736] Launching task '2' for framework 0616b8f0-f9ac-46f6-b370-f315fe93c428-0000
I0202 01:09:13.345921 30206 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_S3sBFL/slaves/0616b8f0-f9ac-46f6-b370-f315fe93c428-S0/frameworks/0616b8f0-f9ac-46f6-b370-f315fe93c428-0000/executors/executor-2/runs/fe0be14c-ec65-46bb-96c7-0cc16d539e4e' to user 'mesos'
I0202 01:09:13.346233 30206 slave.cpp:6331] Launching executor 'executor-2' of framework 0616b8f0-f9ac-46f6-b370-f315fe93c428-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_S3sBFL/slaves/0616b8f0-f9ac-46f6-b370-f315fe93c428-S0/frameworks/0616b8f0-f9ac-46f6-b370-f315fe93c428-0000/executors/executor-2/runs/fe0be14c-ec65-46bb-96c7-0cc16d539e4e'
*** Aborted at 1485997753 (unix time) try "date -d @1485997753" if you are using GNU date ***
PC: @     0x2b821e67b82d (unknown)
I0202 01:09:13.346989 30199 exec.cpp:162] Version: 1.2.0
I0202 01:09:13.347575 30204 exec.cpp:212] Executor started at: executor(75)@172.17.0.3:57175 with pid 30173
*** SIGSEGV (@0xc0) received by PID 30173 (TID 0x2b82284b5700) from PID 192; stack trace: ***
    @     0x2b82747d9197 (unknown)
    @     0x2b82747dd479 (unknown)
    @     0x2b821e432330 (unknown)
    @     0x2b821e67b82d (unknown)
    @     0x2b821451dd96 os::getenv()
    @     0x2b821b43d1e7 mesos::internal::slave::executorEnvironment()
    @     0x2b821b437de8 mesos::internal::slave::Framework::launchExecutor()
    @     0x2b821b412a6b mesos::internal::slave::Slave::_run()
    @     0x2b821b46b269 _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS_6FutureIbEERKNS1_13FrameworkInfoERKNS1_12ExecutorInfoERK6OptionINS1_8TaskInfoEERKSF_INS1_13TaskGroupInfoEES6_S9_SC_SH_SL_EEvRKNS_3PIDIT_EEMSP_FvT0_T1_T2_T3_T4_ET5_T6_T7_T8_T9_ENKUlPNS_11ProcessBaseEE_clES16_
    @     0x2b821b49fdf9 _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchIN5mesos8internal5slave5SlaveERKNS0_6FutureIbEERKNS5_13FrameworkInfoERKNS5_12ExecutorInfoERK6OptionINS5_8TaskInfoEERKSJ_INS5_13TaskGroupInfoEESA_SD_SG_SL_SP_EEvRKNS0_3PIDIT_EEMST_FvT0_T1_T2_T3_T4_ET5_T6_T7_T8_T9_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_
    @     0x2b821c1a8f63 std::function<>::operator()()
    @     0x2b821c18ed65 process::ProcessBase::visit()
    @     0x2b821c1995e4 process::DispatchEvent::visit()
    @     0x2b82144f20dc process::ProcessBase::serve()
    @     0x2b821c18b052 process::ProcessManager::resume()
    @     0x2b821c187896 _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv
    @     0x2b821c198d1e _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
    @     0x2b821c198c75 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv
    @     0x2b821c198c0e _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
    @     0x2b821dcb3a60 (unknown)
    @     0x2b821e42a184 start_thread
    @     0x2b821e73a37d (unknown)
make[4]: *** [check-local] Segmentation fault
make[4]: Leaving directory `/mesos/mesos-1.2.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.2.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.2.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.2.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1485995128-2680
Untagged: mesos-1485995128-2680:latest
Deleted: sha256:de4096f88fa1bf73ca7c4a81b2b3409ed3a0e558e18d98186899b439fbe8e388
Deleted: sha256:ebef9ed68e24bbe5884caf5ef9c19086c5b04748a9fe374c95ec12ddc6d60196
Deleted: sha256:0bd8f392b19c550d5429cbd01089585a98bbd453ace665ed673eed337c61c6e0
Deleted: sha256:5812141e3d5e0868d67561f27e00ab465ff498920012103942bc5accb00761ba
Deleted: sha256:80ae9d7b079fd4d6bdc12e2b2a513ac055f1582b4f251454ae33bf0df1fa9c25
Deleted: sha256:5e787f584e507254292d3cff1ede63b1e0c255f317b035b4764e164db2257bc9
Deleted: sha256:2d502499ec05e5586e9d5bae6cd6892135783a78d50cf88b0c21928f283a2ba4
Deleted: sha256:ced49f3a1898d1b7a68b322bfcb1646142bd603d3d9ddd9e20a81ee327a69ddc
Deleted: sha256:c0ee9d3ab1d75ff1065edc09015e865b99be2a2899718779fe90ea5c7005d10b
Deleted: sha256:bf48cb4bdc0c2659892395ffee1e2bd5bb1a0be6592155b95620de1dbca9188c
Deleted: sha256:37971ced881d4696e68f79b5eef32b8bab79d80fca49aba5d804cf05e7ad2e83
Deleted: sha256:33f554ea574a07185716adecf3f5a9f26328da0ccad01711902e25fdd732001f
Deleted: sha256:f28fb47ec3580df8507495250c5cc574073f54ff8bb235b89e6933abdd56aaff
Deleted: sha256:10dd40b9435c6925451ce560ef3f20f93116038ae94fb49ec088945977ec65c2
Deleted: sha256:640096078c268feffa699af3ce04ec97bcd3bd9c79218d643c48d7b7e8ece504
Deleted: sha256:80f1e541c529a49f057ff55338c7ef751c64965d379394eb3733611b53e9887f
Deleted: sha256:0a8f533ac107f7e28f985e6af79d6deb04407ecdea870ac2627404c83d3c6cca
Deleted: sha256:d8b7a0f37419585c7b4e0d4cba9eff3219a95d6f99165cc1878db2d7dcb42a80
Deleted: sha256:52ffa5480f57a4609ba93dbe43cbca3faa9f861716f468252aa0205d060ba7b2
Deleted: sha256:9d029848f1d63dc3c9161f4acdabb908c672b356960f7555db463016a2fe84f6
Deleted: sha256:d783a2e4080fed27a69e23510873f1d03ed023c8bc7a7bd40e4227fc39ab9a43
Deleted: sha256:11a398156fb54248abe4f15ae3f3bad15b46e3b27419e495a3ce10d9e687a90f
Deleted: sha256:1dc8a1ccb847c64342528e4223b9a9a9e0851197aafcfed1d704c862f3737fe7
Deleted: sha256:70104d1c134a7fb62490530723006a80b7e2cef24547a0ddfa2721a7130147c3
Build step 'Execute shell' marked build as failure

Jenkins build is back to normal : Mesos-Reviewbot #16948

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/16948/>