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/09 21:20:03 UTC
Build failed in Jenkins: Mesos-Reviewbot #17041
See <https://builds.apache.org/job/Mesos-Reviewbot/17041/>
------------------------------------------
[...truncated 70282 lines...]
I0209 21:19:58.789469 30465 slave.cpp:3102] Status update manager successfully handled status update acknowledgement (UUID: 6c05c429-9b75-4738-8c14-51f071ba72d3) for task 1 of framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000
I0209 21:19:58.789520 30473 exec.cpp:400] Executor::frameworkMessage took 47769ns
I0209 21:19:58.790114 30467 slave.cpp:4321] Sending message for framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000 to scheduler-d2413e12-0234-4b86-94a7-3044f81c58ec@172.17.0.2:33648
I0209 21:19:58.790307 30475 sched.cpp:1177] Scheduler::frameworkMessage took 54043ns
I0209 21:19:58.790657 30442 sched.cpp:2021] Asked to stop the driver
I0209 21:19:58.790777 30469 sched.cpp:1203] Stopping framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000
I0209 21:19:58.790951 30463 master.cpp:7715] Processing TEARDOWN call for framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000 (default) at scheduler-d2413e12-0234-4b86-94a7-3044f81c58ec@172.17.0.2:33648
I0209 21:19:58.790982 30463 master.cpp:7727] Removing framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000 (default) at scheduler-d2413e12-0234-4b86-94a7-3044f81c58ec@172.17.0.2:33648
I0209 21:19:58.790999 30463 master.cpp:3081] Deactivating framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000 (default) at scheduler-d2413e12-0234-4b86-94a7-3044f81c58ec@172.17.0.2:33648
I0209 21:19:58.791234 30476 hierarchical.cpp:415] Deactivated framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000
I0209 21:19:58.791307 30463 master.cpp:8312] Updating the state of task 1 of framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0209 21:19:58.791328 30470 slave.cpp:2655] Asked to shut down framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000 by master@172.17.0.2:33648
I0209 21:19:58.791357 30470 slave.cpp:2680] Shutting down framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000
I0209 21:19:58.791391 30470 slave.cpp:5080] Shutting down executor 'default' of framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000 at executor(74)@172.17.0.2:33648
I0209 21:19:58.791512 30472 exec.cpp:410] Executor asked to shutdown
I0209 21:19:58.791555 30472 exec.cpp:425] Executor::shutdown took 23430ns
I0209 21:19:58.791744 30461 slave.cpp:4385] Got exited event for executor(74)@172.17.0.2:33648
I0209 21:19:58.791988 30463 master.cpp:8406] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000 on agent 1d9538ba-ebef-4062-8a72-e21d3c812053-S0 at slave(179)@172.17.0.2:33648 (39d22f6bdeb8)
I0209 21:19:58.792322 30463 master.cpp:8435] Removing executor 'default' with resources {} of framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000 on agent 1d9538ba-ebef-4062-8a72-e21d3c812053-S0 at slave(179)@172.17.0.2:33648 (39d22f6bdeb8)
I0209 21:19:58.792335 30470 hierarchical.cpp:1064] 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 1d9538ba-ebef-4062-8a72-e21d3c812053-S0 from framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000
I0209 21:19:58.792871 30470 hierarchical.cpp:362] Removed framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000
I0209 21:19:58.792924 30472 slave.cpp:4766] Executor 'default' of framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000 exited with status 0
I0209 21:19:58.793007 30472 slave.cpp:4866] Cleaning up executor 'default' of framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000 at executor(74)@172.17.0.2:33648
W0209 21:19:58.793143 30467 master.cpp:6288] Ignoring unknown exited executor 'default' of framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000 on agent 1d9538ba-ebef-4062-8a72-e21d3c812053-S0 at slave(179)@172.17.0.2:33648 (39d22f6bdeb8)
I0209 21:19:58.793293 30467 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_5aoC5A/slaves/1d9538ba-ebef-4062-8a72-e21d3c812053-S0/frameworks/1d9538ba-ebef-4062-8a72-e21d3c812053-0000/executors/default/runs/7b4e275d-a822-4a6c-9d28-556dce91f97f' for gc 6.99999081988444days in the future
I0209 21:19:58.793413 30472 slave.cpp:4954] Cleaning up framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000
I0209 21:19:58.793504 30465 status_update_manager.cpp:285] Closing status update streams for framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000
I0209 21:19:58.793545 30465 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 1d9538ba-ebef-4062-8a72-e21d3c812053-0000
I0209 21:19:58.793570 30467 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_5aoC5A/slaves/1d9538ba-ebef-4062-8a72-e21d3c812053-S0/frameworks/1d9538ba-ebef-4062-8a72-e21d3c812053-0000/executors/default' for gc 6.99999081777778days in the future
I0209 21:19:58.793709 30467 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_5aoC5A/slaves/1d9538ba-ebef-4062-8a72-e21d3c812053-S0/frameworks/1d9538ba-ebef-4062-8a72-e21d3c812053-0000' for gc 6.99999081586667days in the future
I0209 21:19:58.793768 30463 slave.cpp:803] Agent terminating
I0209 21:19:58.794116 30471 master.cpp:1264] Agent 1d9538ba-ebef-4062-8a72-e21d3c812053-S0 at slave(179)@172.17.0.2:33648 (39d22f6bdeb8) disconnected
I0209 21:19:58.794150 30471 master.cpp:3118] Disconnecting agent 1d9538ba-ebef-4062-8a72-e21d3c812053-S0 at slave(179)@172.17.0.2:33648 (39d22f6bdeb8)
I0209 21:19:58.794229 30471 master.cpp:3137] Deactivating agent 1d9538ba-ebef-4062-8a72-e21d3c812053-S0 at slave(179)@172.17.0.2:33648 (39d22f6bdeb8)
I0209 21:19:58.794508 30461 hierarchical.cpp:618] Agent 1d9538ba-ebef-4062-8a72-e21d3c812053-S0 deactivated
I0209 21:19:58.797399 30471 master.cpp:1103] Master terminating
I0209 21:19:58.798419 30475 hierarchical.cpp:549] Removed agent 1d9538ba-ebef-4062-8a72-e21d3c812053-S0
[ OK ] MasterTest.FrameworkMessage (78 ms)
[ RUN ] MasterTest.MultipleExecutors
I0209 21:19:58.807343 30442 cluster.cpp:160] Creating default 'local' authorizer
I0209 21:19:58.810420 30473 master.cpp:383] Master 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa (39d22f6bdeb8) started on 172.17.0.2:33648
I0209 21:19:58.810447 30473 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/ePJRoy/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/ePJRoy/master" --zk_session_timeout="10secs"
I0209 21:19:58.810925 30473 master.cpp:435] Master only allowing authenticated frameworks to register
I0209 21:19:58.810940 30473 master.cpp:449] Master only allowing authenticated agents to register
I0209 21:19:58.810952 30473 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0209 21:19:58.810962 30473 credentials.hpp:37] Loading credentials for authentication from '/tmp/ePJRoy/credentials'
I0209 21:19:58.811293 30473 master.cpp:507] Using default 'crammd5' authenticator
I0209 21:19:58.811446 30473 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0209 21:19:58.811645 30473 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0209 21:19:58.811872 30473 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0209 21:19:58.812240 30473 master.cpp:587] Authorization enabled
I0209 21:19:58.812440 30476 hierarchical.cpp:161] Initialized hierarchical allocator process
I0209 21:19:58.812536 30476 whitelist_watcher.cpp:77] No whitelist given
I0209 21:19:58.815534 30465 master.cpp:2124] Elected as the leading master!
I0209 21:19:58.815567 30465 master.cpp:1646] Recovering from registrar
I0209 21:19:58.815718 30463 registrar.cpp:329] Recovering registrar
I0209 21:19:58.816603 30461 registrar.cpp:362] Successfully fetched the registry (0B) in 834048ns
I0209 21:19:58.816761 30461 registrar.cpp:461] Applied 1 operations in 24504ns; attempting to update the registry
I0209 21:19:58.817689 30464 registrar.cpp:506] Successfully updated the registry in 860928ns
I0209 21:19:58.817834 30464 registrar.cpp:392] Successfully recovered registrar
I0209 21:19:58.818847 30468 master.cpp:1762] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0209 21:19:58.818918 30470 hierarchical.cpp:188] Skipping recovery of hierarchical allocator: nothing to recover
I0209 21:19:58.824463 30442 cluster.cpp:446] Creating default 'local' authorizer
I0209 21:19:58.826184 30471 slave.cpp:211] Mesos agent started on (180)@172.17.0.2:33648
I0209 21:19:58.826210 30471 slave.cpp:212] 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_gWaAlx/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_gWaAlx/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_gWaAlx/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_gWaAlx" --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_qKddSx"
I0209 21:19:58.826632 30471 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_gWaAlx/credential'
I0209 21:19:58.826798 30471 slave.cpp:354] Agent using credential for: test-principal
I0209 21:19:58.826819 30471 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_gWaAlx/http_credentials'
I0209 21:19:58.827055 30471 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0209 21:19:58.827349 30471 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0209 21:19:58.827371 30442 sched.cpp:232] Version: 1.2.0
I0209 21:19:58.827989 30474 sched.cpp:336] New master detected at master@172.17.0.2:33648
I0209 21:19:58.828106 30474 sched.cpp:407] Authenticating with master master@172.17.0.2:33648
I0209 21:19:58.828135 30474 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0209 21:19:58.828392 30472 authenticatee.cpp:121] Creating new client SASL connection
I0209 21:19:58.828717 30475 master.cpp:7173] Authenticating scheduler-0da78789-e6c9-4b96-944d-b8ce54e137ac@172.17.0.2:33648
I0209 21:19:58.828843 30467 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(412)@172.17.0.2:33648
I0209 21:19:58.829156 30461 authenticator.cpp:98] Creating new server SASL connection
I0209 21:19:58.829181 30471 slave.cpp:541] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0209 21:19:58.829285 30471 slave.cpp:549] Agent attributes: [ ]
I0209 21:19:58.829301 30471 slave.cpp:554] Agent hostname: 39d22f6bdeb8
I0209 21:19:58.829411 30469 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0209 21:19:58.829440 30465 status_update_manager.cpp:177] Pausing sending status updates
I0209 21:19:58.829458 30469 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0209 21:19:58.829591 30461 authenticator.cpp:204] Received SASL authentication start
I0209 21:19:58.829659 30461 authenticator.cpp:326] Authentication requires more steps
I0209 21:19:58.829764 30461 authenticatee.cpp:259] Received SASL authentication step
I0209 21:19:58.829983 30476 authenticator.cpp:232] Received SASL authentication step
I0209 21:19:58.830029 30476 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '39d22f6bdeb8' server FQDN: '39d22f6bdeb8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0209 21:19:58.830059 30476 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0209 21:19:58.830114 30476 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0209 21:19:58.830168 30476 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '39d22f6bdeb8' server FQDN: '39d22f6bdeb8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0209 21:19:58.830196 30476 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0209 21:19:58.830216 30476 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0209 21:19:58.830245 30476 authenticator.cpp:318] Authentication success
I0209 21:19:58.830368 30472 authenticatee.cpp:299] Authentication success
I0209 21:19:58.830399 30467 master.cpp:7203] Successfully authenticated principal 'test-principal' at scheduler-0da78789-e6c9-4b96-944d-b8ce54e137ac@172.17.0.2:33648
I0209 21:19:58.830549 30474 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(412)@172.17.0.2:33648
I0209 21:19:58.830974 30475 sched.cpp:513] Successfully authenticated with master master@172.17.0.2:33648
I0209 21:19:58.831001 30475 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.2:33648
I0209 21:19:58.831125 30475 sched.cpp:869] Will retry registration in 72.372365ms if necessary
I0209 21:19:58.831322 30473 master.cpp:2737] Received SUBSCRIBE call for framework 'default' at scheduler-0da78789-e6c9-4b96-944d-b8ce54e137ac@172.17.0.2:33648
I0209 21:19:58.831409 30473 master.cpp:2160] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0209 21:19:58.831480 30461 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_qKddSx/meta'
I0209 21:19:58.831859 30476 status_update_manager.cpp:203] Recovering status update manager
I0209 21:19:58.831949 30464 master.cpp:2813] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0209 21:19:58.832408 30463 slave.cpp:5555] Finished recovery
I0209 21:19:58.832634 30476 hierarchical.cpp:286] Added framework 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000
I0209 21:19:58.832715 30465 sched.cpp:759] Framework registered with 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000
I0209 21:19:58.832770 30465 sched.cpp:773] Scheduler::registered took 27114ns
I0209 21:19:58.832864 30476 hierarchical.cpp:1810] No allocations performed
I0209 21:19:58.832918 30476 hierarchical.cpp:1900] No inverse offers to send out!
I0209 21:19:58.832986 30476 hierarchical.cpp:1394] Performed allocation for 0 agents in 168003ns
I0209 21:19:58.833189 30463 slave.cpp:5729] Querying resource estimator for oversubscribable resources
I0209 21:19:58.833539 30461 slave.cpp:931] New master detected at master@172.17.0.2:33648
I0209 21:19:58.833565 30462 status_update_manager.cpp:177] Pausing sending status updates
I0209 21:19:58.833665 30461 slave.cpp:966] Detecting new master
I0209 21:19:58.833842 30461 slave.cpp:5743] Received oversubscribable resources {} from the resource estimator
I0209 21:19:58.835968 30475 slave.cpp:993] Authenticating with master master@172.17.0.2:33648
I0209 21:19:58.836052 30475 slave.cpp:1004] Using default CRAM-MD5 authenticatee
I0209 21:19:58.836330 30466 authenticatee.cpp:121] Creating new client SASL connection
I0209 21:19:58.836635 30463 master.cpp:7173] Authenticating slave(180)@172.17.0.2:33648
I0209 21:19:58.836808 30462 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(413)@172.17.0.2:33648
I0209 21:19:58.837102 30472 authenticator.cpp:98] Creating new server SASL connection
I0209 21:19:58.837332 30469 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0209 21:19:58.837369 30469 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0209 21:19:58.837514 30461 authenticator.cpp:204] Received SASL authentication start
I0209 21:19:58.837579 30461 authenticator.cpp:326] Authentication requires more steps
I0209 21:19:58.837719 30472 authenticatee.cpp:259] Received SASL authentication step
I0209 21:19:58.837903 30476 authenticator.cpp:232] Received SASL authentication step
I0209 21:19:58.837950 30476 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '39d22f6bdeb8' server FQDN: '39d22f6bdeb8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0209 21:19:58.837971 30476 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0209 21:19:58.838011 30476 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0209 21:19:58.838037 30476 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '39d22f6bdeb8' server FQDN: '39d22f6bdeb8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0209 21:19:58.838052 30476 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0209 21:19:58.838063 30476 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0209 21:19:58.838088 30476 authenticator.cpp:318] Authentication success
I0209 21:19:58.838192 30463 authenticatee.cpp:299] Authentication success
I0209 21:19:58.838256 30473 master.cpp:7203] Successfully authenticated principal 'test-principal' at slave(180)@172.17.0.2:33648
I0209 21:19:58.838421 30470 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(413)@172.17.0.2:33648
I0209 21:19:58.838644 30472 slave.cpp:1088] Successfully authenticated with master master@172.17.0.2:33648
I0209 21:19:58.838847 30472 slave.cpp:1515] Will retry registration in 7.807464ms if necessary
I0209 21:19:58.839059 30463 master.cpp:5388] Registering agent at slave(180)@172.17.0.2:33648 (39d22f6bdeb8) with id 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0
I0209 21:19:58.839550 30462 registrar.cpp:461] Applied 1 operations in 54347ns; attempting to update the registry
I0209 21:19:58.845023 30462 registrar.cpp:506] Successfully updated the registry in 5.392896ms
I0209 21:19:58.845937 30472 slave.cpp:4343] Received ping from slave-observer(178)@172.17.0.2:33648
I0209 21:19:58.845999 30473 master.cpp:5462] Registered agent 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0 at slave(180)@172.17.0.2:33648 (39d22f6bdeb8) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0209 21:19:58.846122 30472 slave.cpp:1134] Registered with master master@172.17.0.2:33648; given agent ID 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0
I0209 21:19:58.846160 30472 fetcher.cpp:90] Clearing fetcher cache
I0209 21:19:58.846351 30471 status_update_manager.cpp:184] Resuming sending status updates
I0209 21:19:58.846391 30463 hierarchical.cpp:516] Added agent 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0 (39d22f6bdeb8) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0209 21:19:58.846612 30472 slave.cpp:1162] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_qKddSx/meta/slaves/0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0/slave.info'
I0209 21:19:58.847021 30472 slave.cpp:1200] Forwarding total oversubscribed resources {}
I0209 21:19:58.847208 30472 master.cpp:6019] Received update of agent 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0 at slave(180)@172.17.0.2:33648 (39d22f6bdeb8) with total oversubscribed resources {}
I0209 21:19:58.847733 30463 hierarchical.cpp:1900] No inverse offers to send out!
I0209 21:19:58.847800 30463 hierarchical.cpp:1394] Performed allocation for 1 agents in 1.26174ms
I0209 21:19:58.847947 30463 hierarchical.cpp:589] Agent 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0 (39d22f6bdeb8) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000])
I0209 21:19:58.848155 30463 hierarchical.cpp:1810] No allocations performed
I0209 21:19:58.848207 30463 hierarchical.cpp:1900] No inverse offers to send out!
I0209 21:19:58.848268 30463 hierarchical.cpp:1394] Performed allocation for 1 agents in 194312ns
I0209 21:19:58.848520 30476 master.cpp:7002] Sending 1 offers to framework 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000 (default) at scheduler-0da78789-e6c9-4b96-944d-b8ce54e137ac@172.17.0.2:33648
I0209 21:19:58.849287 30474 sched.cpp:933] Scheduler::resourceOffers took 195215ns
I0209 21:19:58.851655 30467 master.cpp:3776] Processing ACCEPT call for offers: [ 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-O0 ] on agent 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0 at slave(180)@172.17.0.2:33648 (39d22f6bdeb8) for framework 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000 (default) at scheduler-0da78789-e6c9-4b96-944d-b8ce54e137ac@172.17.0.2:33648
I0209 21:19:58.851784 30467 master.cpp:3352] Authorizing framework principal 'test-principal' to launch task 1
I0209 21:19:58.852125 30467 master.cpp:3352] Authorizing framework principal 'test-principal' to launch task 2
W0209 21:19:58.854778 30469 validation.cpp:1064] 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.
W0209 21:19:58.854816 30469 validation.cpp:1076] 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.
I0209 21:19:58.855216 30469 master.cpp:9063] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0 at slave(180)@172.17.0.2:33648 (39d22f6bdeb8)
I0209 21:19:58.855409 30469 master.cpp:4426] Launching task 1 of framework 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000 (default) at scheduler-0da78789-e6c9-4b96-944d-b8ce54e137ac@172.17.0.2:33648 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0 at slave(180)@172.17.0.2:33648 (39d22f6bdeb8)
I0209 21:19:58.855924 30463 slave.cpp:1625] Got assigned task '1' for framework 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000
I0209 21:19:58.856911 30463 slave.cpp:1785] Launching task '1' for framework 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000
W0209 21:19:58.857498 30469 validation.cpp:1064] 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.
W0209 21:19:58.857552 30469 validation.cpp:1076] 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.
I0209 21:19:58.857930 30463 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_qKddSx/slaves/0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0/frameworks/0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000/executors/executor-1/runs/302b7fee-6dde-4bd8-a310-c559a67383aa' to user 'mesos'
I0209 21:19:58.858270 30463 slave.cpp:6477] Launching executor 'executor-1' of framework 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_qKddSx/slaves/0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0/frameworks/0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000/executors/executor-1/runs/302b7fee-6dde-4bd8-a310-c559a67383aa'
I0209 21:19:58.858254 30469 master.cpp:9063] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0 at slave(180)@172.17.0.2:33648 (39d22f6bdeb8)
I0209 21:19:58.858566 30469 master.cpp:4426] Launching task 2 of framework 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000 (default) at scheduler-0da78789-e6c9-4b96-944d-b8ce54e137ac@172.17.0.2:33648 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0 at slave(180)@172.17.0.2:33648 (39d22f6bdeb8)
I0209 21:19:58.859083 30463 slave.cpp:2115] Queued task '1' for executor 'executor-1' of framework 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000
I0209 21:19:58.859181 30463 slave.cpp:884] Successfully attached file '/tmp/MasterTest_MultipleExecutors_qKddSx/slaves/0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0/frameworks/0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000/executors/executor-1/runs/302b7fee-6dde-4bd8-a310-c559a67383aa'
I0209 21:19:58.859493 30463 slave.cpp:1625] Got assigned task '2' for framework 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000
I0209 21:19:58.860054 30463 slave.cpp:1785] Launching task '2' for framework 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000
I0209 21:19:58.860600 30463 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_qKddSx/slaves/0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0/frameworks/0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000/executors/executor-2/runs/48dbce73-6a33-47ac-a857-bfa2323fa348' to user 'mesos'
I0209 21:19:58.860847 30463 slave.cpp:6477] Launching executor 'executor-2' of framework 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_qKddSx/slaves/0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0/frameworks/0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000/executors/executor-2/runs/48dbce73-6a33-47ac-a857-bfa2323fa348'
*** Aborted at 1486675198 (unix time) try "date -d @1486675198" if you are using GNU date ***
PC: @ 0x2ad6b564f82d (unknown)
I0209 21:19:58.861356 30468 exec.cpp:162] Version: 1.2.0
*** SIGSEGV (@0xd0) received by PID 30442 (TID 0x2ad6bdc83700) from PID 208; stack trace: ***
I0209 21:19:58.861567 30467 exec.cpp:212] Executor started at: executor(75)@172.17.0.2:33648 with pid 30442
I0209 21:19:58.862015 30472 hierarchical.cpp:807] Updated allocation of framework 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000 on agent 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0 from cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] to cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000]
@ 0x2ad6eebbb2d7 (unknown)
I0209 21:19:58.862903 30472 hierarchical.cpp:1064] Recovered ports(*)(allocated: *):[31000-32000]; disk(*)(allocated: *):1024 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024) on agent 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0 from framework 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000
I0209 21:19:58.862970 30472 hierarchical.cpp:1101] Framework 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-0000 filtered agent 0fa8b0fb-5c65-4dde-a1e9-a5a9363aa0aa-S0 for 5secs
@ 0x2ad6eebbf5b9 (unknown)
@ 0x2ad6b5406330 (unknown)
@ 0x2ad6b564f82d (unknown)
@ 0x2ad6ab387596 os::getenv()
@ 0x2ad6b23a739d mesos::internal::slave::executorEnvironment()
@ 0x2ad6b23a1b70 mesos::internal::slave::Framework::launchExecutor()
@ 0x2ad6b237b9b8 mesos::internal::slave::Slave::_run()
@ 0x2ad6b23d56bb _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_
@ 0x2ad6b240a439 _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_
@ 0x2ad6b3148219 std::function<>::operator()()
@ 0x2ad6b312e0e9 process::ProcessBase::visit()
@ 0x2ad6b3138968 process::DispatchEvent::visit()
@ 0x2ad6ab35b8dc process::ProcessBase::serve()
@ 0x2ad6b312a3d6 process::ProcessManager::resume()
@ 0x2ad6b3126c1a _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv
@ 0x2ad6b31380a2 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2ad6b3137ff9 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv
@ 0x2ad6b3137f92 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x2ad6b4c87a60 (unknown)
@ 0x2ad6b53fe184 start_thread
@ 0x2ad6b570e37d (unknown)
make[4]: *** [check-local] Segmentation fault (core dumped)
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-1486672854-20948
Untagged: mesos-1486672854-20948:latest
Deleted: sha256:dc2e6085b8babe0dec09967b326ba9d91e1e5cf7f1276a538de7ca3b18b497b0
Deleted: sha256:530db44cfef5884718c8acbabb1e741fa4218372025d00991463e5bbde0886f8
Deleted: sha256:1a93d2a9ccde68e7457b834161356d3f86f3ed1c791e48ad22d6d4d103a126c0
Deleted: sha256:50d2520c2258ca6242ed225063ea640930e3acab50d721cb0a9adf74106f35a4
Deleted: sha256:6396568e8084a252eb00bdb3b2acd3f48dc0419522a44e6804eb0941920f3cf9
Deleted: sha256:1f1b955ecd64790fa41859ee5ef4fdb98f40aa880778672728093fb2557342a2
Deleted: sha256:66d80b409bb10aaf2e8b3d181d3ec38b01c90c48a6d72a19ad35f18070426d2a
Deleted: sha256:1eb7d0d689362ddfe51d2d2d336704ea73ba2f92aa956c4b160a5380d3b335de
Deleted: sha256:3526425d0a994b5dae0f9a00ce1ee9f67a070659098ed025850d8c0eaeecd1da
Deleted: sha256:f79c2189ed1f80b180fa6aabb8b98ba3d845ec79bd95b0f626e75418d22bedad
Deleted: sha256:b39e5e71372521ade9bf3981cc874fa274684b3797f5ffbb20d7a02f737d8ec5
Deleted: sha256:793711886f1c96bfd0f4608c7838212862233564259844c26936a74f121e5271
Deleted: sha256:674f68e6091b8499b354752481139656edc19378d533bddb756ade05dddb1626
Deleted: sha256:681321a0137e5a7b0067e9b534a5bdaa664e92af9b451a9a6e67a7b7ecf0cb12
Deleted: sha256:de2bf97e2ac832967bd3fce784100688de7fa5300665642a304191bf205b3f8f
Deleted: sha256:59cfe0a9136b92e130f5e30f4d02a58ff3d71c05461f72de83381ecc30389922
Deleted: sha256:bb383fd17d1468374c3afcd9758a009a76b533fd5e211a8271ecf1a0f63a46f6
Deleted: sha256:bd5d73e00484008b488ed94fe721a0a484691eb585173c5a63f642bfed12adf6
Deleted: sha256:23da1cf8ce4386e841d62ebce5290546e937ae5e6e5767309b249b121024867b
Deleted: sha256:6a31f9c27bb064772ece7cf37eb150d452f17a8370506bf3bc0193ffc6b177fb
Deleted: sha256:aa09350b0e48abb71ccdd5d862b183afde65e974dcedad064db48f74a8a22e94
Deleted: sha256:3c6d8687a8228344e5b865576070f498fab0a170841587f9de2fd0f28cce6294
Deleted: sha256:bb8df72b89967973fb7c581269a2db5f8532dbb531dff981690f9bc696ec91d2
Deleted: sha256:355f3bdd14fda1ad37c74460b2a91533478cd5eb3f5ef39c84e0bd0c0dcb8377
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Reviewbot #17042
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/17042/>