See <https://builds.apache.org/job/mesos-reviewbot/16085/>
------------------------------------------ [...truncated 221575 lines...] I1116 02:59:48.024572 29718 http.cpp:895] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I1116 02:59:48.024711 29718 http.cpp:895] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I1116 02:59:48.024858 29718 master.cpp:584] Authorization enabled I1116 02:59:48.025087 29716 hierarchical.cpp:149] Initialized hierarchical allocator process I1116 02:59:48.025157 29726 whitelist_watcher.cpp:77] No whitelist given I1116 02:59:48.027534 29720 master.cpp:2033] Elected as the leading master! I1116 02:59:48.027576 29720 master.cpp:1560] Recovering from registrar I1116 02:59:48.027802 29726 registrar.cpp:329] Recovering registrar I1116 02:59:48.043566 29724 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 20.815219ms I1116 02:59:48.043643 29724 replica.cpp:320] Persisted replica status to STARTING I1116 02:59:48.044006 29724 recover.cpp:477] Replica is in STARTING status I1116 02:59:48.045140 29721 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from __req_res__(8102)@172.17.0.2:51027 I1116 02:59:48.045488 29717 recover.cpp:197] Received a recover response from a replica in STARTING status I1116 02:59:48.045913 29729 recover.cpp:568] Updating replica status to VOTING I1116 02:59:48.077621 29717 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 31.413329ms I1116 02:59:48.077668 29717 replica.cpp:320] Persisted replica status to VOTING I1116 02:59:48.077781 29717 recover.cpp:582] Successfully joined the Paxos group I1116 02:59:48.077988 29717 recover.cpp:466] Recover process terminated I1116 02:59:48.078449 29722 log.cpp:553] Attempting to start the writer I1116 02:59:48.079674 29719 replica.cpp:493] Replica received implicit promise request from __req_res__(8103)@172.17.0.2:51027 with proposal 1 I1116 02:59:48.113306 29719 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 33.583938ms I1116 02:59:48.113376 29719 replica.cpp:342] Persisted promised to 1 I1116 02:59:48.114609 29719 coordinator.cpp:238] Coordinator attempting to fill missing positions I1116 02:59:48.116055 29724 replica.cpp:388] Replica received explicit promise request from __req_res__(8104)@172.17.0.2:51027 for position 0 with proposal 2 I1116 02:59:48.160696 29724 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 44.575129ms I1116 02:59:48.160765 29724 replica.cpp:708] Persisted action NOP at position 0 I1116 02:59:48.162185 29718 replica.cpp:537] Replica received write request for position 0 from __req_res__(8105)@172.17.0.2:51027 I1116 02:59:48.162272 29718 leveldb.cpp:436] Reading position from leveldb took 44795ns I1116 02:59:48.211031 29718 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 48.696844ms I1116 02:59:48.211096 29718 replica.cpp:708] Persisted action NOP at position 0 I1116 02:59:48.212025 29728 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0 I1116 02:59:48.261438 29728 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 49.365325ms I1116 02:59:48.261509 29728 replica.cpp:708] Persisted action NOP at position 0 I1116 02:59:48.262434 29722 log.cpp:569] Writer started with ending position 0 I1116 02:59:48.263701 29725 leveldb.cpp:436] Reading position from leveldb took 62383ns I1116 02:59:48.264935 29730 registrar.cpp:362] Successfully fetched the registry (0B) in 237.072384ms I1116 02:59:48.265070 29730 registrar.cpp:461] Applied 1 operations in 21712ns; attempting to update the registry I1116 02:59:48.265877 29726 log.cpp:577] Attempting to append 168 bytes to the log I1116 02:59:48.266072 29725 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1 I1116 02:59:48.266973 29727 replica.cpp:537] Replica received write request for position 1 from __req_res__(8106)@172.17.0.2:51027 I1116 02:59:48.311148 29727 leveldb.cpp:341] Persisting action (187 bytes) to leveldb took 44.133046ms I1116 02:59:48.311193 29727 replica.cpp:708] Persisted action APPEND at position 1 I1116 02:59:48.311976 29730 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0 I1116 02:59:48.344471 29730 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 32.452722ms I1116 02:59:48.344511 29730 replica.cpp:708] Persisted action APPEND at position 1 I1116 02:59:48.345988 29726 registrar.cpp:506] Successfully updated the registry in 80.834048ms I1116 02:59:48.346189 29726 registrar.cpp:392] Successfully recovered registrar I1116 02:59:48.346242 29721 log.cpp:596] Attempting to truncate the log to 1 I1116 02:59:48.346575 29717 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2 I1116 02:59:48.347172 29722 master.cpp:1676] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I1116 02:59:48.347203 29724 hierarchical.cpp:176] Skipping recovery of hierarchical allocator: nothing to recover I1116 02:59:48.348672 29730 replica.cpp:537] Replica received write request for position 2 from __req_res__(8107)@172.17.0.2:51027 I1116 02:59:48.390697 29730 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 41.972432ms I1116 02:59:48.390743 29730 replica.cpp:708] Persisted action TRUNCATE at position 2 I1116 02:59:48.391531 29725 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0 I1116 02:59:48.415834 29725 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 24.208309ms I1116 02:59:48.415910 29725 leveldb.cpp:399] Deleting ~1 keys from leveldb took 38170ns I1116 02:59:48.415932 29725 replica.cpp:708] Persisted action TRUNCATE at position 2 I1116 02:59:48.420805 29696 cluster.cpp:435] Creating default 'local' authorizer I1116 02:59:48.422308 29715 slave.cpp:208] Mesos agent started on (630)@172.17.0.2:51027 I1116 02:59:48.422332 29715 slave.cpp:209] 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/ContentType_SchedulerTest_KillTask_1_mhBKt6/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/ContentType_SchedulerTest_KillTask_1_mhBKt6/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/ContentType_SchedulerTest_KillTask_1_mhBKt6/http_credentials" --image_provisioner_backend="copy" --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/ContentType_SchedulerTest_KillTask_1_mhBKt6" --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/ContentType_SchedulerTest_KillTask_1_YxFqcj" I1116 02:59:48.422735 29715 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_SchedulerTest_KillTask_1_mhBKt6/credential' I1116 02:59:48.422801 29696 scheduler.cpp:176] Version: 1.2.0 I1116 02:59:48.422880 29715 slave.cpp:346] Agent using credential for: test-principal I1116 02:59:48.422905 29715 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_SchedulerTest_KillTask_1_mhBKt6/http_credentials' I1116 02:59:48.423243 29715 http.cpp:895] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I1116 02:59:48.423480 29715 http.cpp:895] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I1116 02:59:48.423528 29726 scheduler.cpp:469] New master detected at [email protected]:51027 I1116 02:59:48.423560 29726 scheduler.cpp:478] Waiting for 0ns before initiating a re-(connection) attempt with the master I1116 02:59:48.424496 29715 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1116 02:59:48.424572 29715 slave.cpp:541] Agent attributes: [ ] I1116 02:59:48.424585 29715 slave.cpp:546] Agent hostname: 28c54315c3ec I1116 02:59:48.426072 29720 state.cpp:57] Recovering state from '/tmp/ContentType_SchedulerTest_KillTask_1_YxFqcj/meta' I1116 02:59:48.426501 29719 status_update_manager.cpp:203] Recovering status update manager I1116 02:59:48.426888 29727 slave.cpp:5411] Finished recovery I1116 02:59:48.426995 29721 scheduler.cpp:353] Connected with the master at http://172.17.0.2:51027/master/api/v1/scheduler I1116 02:59:48.427335 29727 slave.cpp:5585] Querying resource estimator for oversubscribable resources I1116 02:59:48.427594 29716 slave.cpp:5599] Received oversubscribable resources {} from the resource estimator I1116 02:59:48.427832 29715 status_update_manager.cpp:177] Pausing sending status updates I1116 02:59:48.427830 29716 slave.cpp:915] New master detected at [email protected]:51027 I1116 02:59:48.427896 29716 slave.cpp:974] Authenticating with master [email protected]:51027 I1116 02:59:48.428005 29716 slave.cpp:985] Using default CRAM-MD5 authenticatee I1116 02:59:48.428171 29716 slave.cpp:947] Detecting new master I1116 02:59:48.428314 29729 authenticatee.cpp:121] Creating new client SASL connection I1116 02:59:48.428508 29718 scheduler.cpp:235] Sending SUBSCRIBE call to http://172.17.0.2:51027/master/api/v1/scheduler I1116 02:59:48.428622 29730 master.cpp:6745] Authenticating slave(630)@172.17.0.2:51027 I1116 02:59:48.428725 29727 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1209)@172.17.0.2:51027 I1116 02:59:48.428941 29724 authenticator.cpp:98] Creating new server SASL connection I1116 02:59:48.429178 29720 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I1116 02:59:48.429208 29720 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I1116 02:59:48.429316 29727 authenticator.cpp:204] Received SASL authentication start I1116 02:59:48.429383 29727 authenticator.cpp:326] Authentication requires more steps I1116 02:59:48.429481 29726 authenticatee.cpp:259] Received SASL authentication step I1116 02:59:48.429596 29726 authenticator.cpp:232] Received SASL authentication step I1116 02:59:48.429631 29726 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '28c54315c3ec' server FQDN: '28c54315c3ec' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1116 02:59:48.429653 29726 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I1116 02:59:48.429744 29727 process.cpp:3570] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1116 02:59:48.429755 29726 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1116 02:59:48.429831 29726 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '28c54315c3ec' server FQDN: '28c54315c3ec' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1116 02:59:48.429901 29726 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1116 02:59:48.429919 29726 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1116 02:59:48.429940 29726 authenticator.cpp:318] Authentication success I1116 02:59:48.430124 29720 authenticatee.cpp:299] Authentication success I1116 02:59:48.430251 29729 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1209)@172.17.0.2:51027 I1116 02:59:48.430341 29727 master.cpp:6775] Successfully authenticated principal 'test-principal' at slave(630)@172.17.0.2:51027 I1116 02:59:48.430618 29723 slave.cpp:1069] Successfully authenticated with master [email protected]:51027 I1116 02:59:48.430794 29723 slave.cpp:1483] Will retry registration in 1.364096ms if necessary I1116 02:59:48.430963 29719 master.cpp:5154] Registering agent at slave(630)@172.17.0.2:51027 (28c54315c3ec) with id 0fbce162-fb23-40ec-a11f-81576f9191a0-S0 I1116 02:59:48.431252 29719 http.cpp:391] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:43387 I1116 02:59:48.431407 29715 registrar.cpp:461] Applied 1 operations in 67536ns; attempting to update the registry I1116 02:59:48.431644 29719 master.cpp:2329] Received subscription request for HTTP framework 'default' I1116 02:59:48.431712 29719 master.cpp:2069] Authorizing framework principal 'test-principal' to receive offers for role '*' I1116 02:59:48.432131 29728 master.cpp:2427] Subscribing framework 'default' with checkpointing disabled and capabilities [ ] I1116 02:59:48.432370 29721 log.cpp:577] Attempting to append 337 bytes to the log I1116 02:59:48.432564 29718 slave.cpp:1483] Will retry registration in 14.749386ms if necessary I1116 02:59:48.432765 29715 hierarchical.cpp:275] Added framework 0fbce162-fb23-40ec-a11f-81576f9191a0-0000 I1116 02:59:48.432845 29715 hierarchical.cpp:1694] No allocations performed I1116 02:59:48.432857 29729 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 3 I1116 02:59:48.432976 29715 hierarchical.cpp:1789] No inverse offers to send out! I1116 02:59:48.433065 29715 hierarchical.cpp:1286] Performed allocation for 0 agents in 273702ns I1116 02:59:48.433099 29716 master.hpp:2161] Sending heartbeat to 0fbce162-fb23-40ec-a11f-81576f9191a0-0000 I1116 02:59:48.433135 29728 master.cpp:5142] Ignoring register agent message from slave(630)@172.17.0.2:51027 (28c54315c3ec) as admission is already in progress I1116 02:59:48.433917 29723 replica.cpp:537] Replica received write request for position 3 from __req_res__(8108)@172.17.0.2:51027 I1116 02:59:48.434396 29722 scheduler.cpp:675] Enqueuing event SUBSCRIBED received from http://172.17.0.2:51027/master/api/v1/scheduler I1116 02:59:48.434892 29722 scheduler.cpp:675] Enqueuing event HEARTBEAT received from http://172.17.0.2:51027/master/api/v1/scheduler I1116 02:59:48.448474 29716 slave.cpp:1483] Will retry registration in 37.30815ms if necessary I1116 02:59:48.448626 29729 master.cpp:5142] Ignoring register agent message from slave(630)@172.17.0.2:51027 (28c54315c3ec) as admission is already in progress I1116 02:59:48.462033 29723 leveldb.cpp:341] Persisting action (356 bytes) to leveldb took 28.072366ms I1116 02:59:48.462083 29723 replica.cpp:708] Persisted action APPEND at position 3 I1116 02:59:48.462805 29719 replica.cpp:691] Replica received learned notice for position 3 from @0.0.0.0:0 I1116 02:59:48.487331 29722 slave.cpp:1483] Will retry registration in 85.141525ms if necessary I1116 02:59:48.487331 29719 leveldb.cpp:341] Persisting action (358 bytes) to leveldb took 24.491529ms I1116 02:59:48.487443 29719 replica.cpp:708] Persisted action APPEND at position 3 I1116 02:59:48.487671 29722 master.cpp:5142] Ignoring register agent message from slave(630)@172.17.0.2:51027 (28c54315c3ec) as admission is already in progress I1116 02:59:48.489298 29730 registrar.cpp:506] Successfully updated the registry in 57.802752ms I1116 02:59:48.489578 29723 log.cpp:596] Attempting to truncate the log to 3 I1116 02:59:48.489969 29723 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 4 I1116 02:59:48.490388 29718 master.cpp:5225] Registered agent 0fbce162-fb23-40ec-a11f-81576f9191a0-S0 at slave(630)@172.17.0.2:51027 (28c54315c3ec) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1116 02:59:48.490607 29718 slave.cpp:1115] Registered with master [email protected]:51027; given agent ID 0fbce162-fb23-40ec-a11f-81576f9191a0-S0 I1116 02:59:48.490634 29718 fetcher.cpp:86] Clearing fetcher cache I1116 02:59:48.490852 29723 hierarchical.cpp:485] Added agent 0fbce162-fb23-40ec-a11f-81576f9191a0-S0 (28c54315c3ec) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I1116 02:59:48.491098 29718 slave.cpp:1138] Checkpointing SlaveInfo to '/tmp/ContentType_SchedulerTest_KillTask_1_YxFqcj/meta/slaves/0fbce162-fb23-40ec-a11f-81576f9191a0-S0/slave.info' I1116 02:59:48.491557 29718 slave.cpp:1175] Forwarding total oversubscribed resources {} I1116 02:59:48.491672 29718 slave.cpp:4263] Received ping from slave-observer(566)@172.17.0.2:51027 I1116 02:59:48.491844 29723 hierarchical.cpp:1789] No inverse offers to send out! I1116 02:59:48.491909 29723 hierarchical.cpp:1309] Performed allocation for agent 0fbce162-fb23-40ec-a11f-81576f9191a0-S0 in 1.001035ms I1116 02:59:48.491926 29718 status_update_manager.cpp:184] Resuming sending status updates I1116 02:59:48.492144 29725 master.cpp:5624] Received update of agent 0fbce162-fb23-40ec-a11f-81576f9191a0-S0 at slave(630)@172.17.0.2:51027 (28c54315c3ec) with total oversubscribed resources {} I1116 02:59:48.492523 29726 hierarchical.cpp:555] Agent 0fbce162-fb23-40ec-a11f-81576f9191a0-S0 (28c54315c3ec) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]) I1116 02:59:48.492837 29726 hierarchical.cpp:1694] No allocations performed I1116 02:59:48.492884 29726 hierarchical.cpp:1789] No inverse offers to send out! I1116 02:59:48.493161 29726 hierarchical.cpp:1309] Performed allocation for agent 0fbce162-fb23-40ec-a11f-81576f9191a0-S0 in 426030ns I1116 02:59:48.493068 29721 replica.cpp:537] Replica received write request for position 4 from __req_res__(8109)@172.17.0.2:51027 I1116 02:59:48.493718 29725 master.cpp:6574] Sending 1 offers to framework 0fbce162-fb23-40ec-a11f-81576f9191a0-0000 (default) I1116 02:59:48.496990 29718 scheduler.cpp:675] Enqueuing event OFFERS received from http://172.17.0.2:51027/master/api/v1/scheduler I1116 02:59:48.499491 29730 scheduler.cpp:235] Sending ACCEPT call to http://172.17.0.2:51027/master/api/v1/scheduler I1116 02:59:48.501432 29717 process.cpp:3570] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1116 02:59:48.502393 29716 http.cpp:391] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:43386 I1116 02:59:48.504145 29716 master.cpp:3581] Processing ACCEPT call for offers: [ 0fbce162-fb23-40ec-a11f-81576f9191a0-O0 ] on agent 0fbce162-fb23-40ec-a11f-81576f9191a0-S0 at slave(630)@172.17.0.2:51027 (28c54315c3ec) for framework 0fbce162-fb23-40ec-a11f-81576f9191a0-0000 (default) I1116 02:59:48.504240 29716 master.cpp:3173] Authorizing framework principal 'test-principal' to launch task 15b24638-8680-445e-9545-8caec46b4cc0 W1116 02:59:48.506026 29723 validation.cpp:920] Executor 'default' for task '15b24638-8680-445e-9545-8caec46b4cc0' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W1116 02:59:48.506062 29723 validation.cpp:932] Executor 'default' for task '15b24638-8680-445e-9545-8caec46b4cc0' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I1116 02:59:48.506521 29723 master.cpp:8337] Adding task 15b24638-8680-445e-9545-8caec46b4cc0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 0fbce162-fb23-40ec-a11f-81576f9191a0-S0 (28c54315c3ec) I1116 02:59:48.506784 29723 master.cpp:4233] Launching task 15b24638-8680-445e-9545-8caec46b4cc0 of framework 0fbce162-fb23-40ec-a11f-81576f9191a0-0000 (default) with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 0fbce162-fb23-40ec-a11f-81576f9191a0-S0 at slave(630)@172.17.0.2:51027 (28c54315c3ec) I1116 02:59:48.507256 29724 slave.cpp:1547] Got assigned task '15b24638-8680-445e-9545-8caec46b4cc0' for framework 0fbce162-fb23-40ec-a11f-81576f9191a0-0000 I1116 02:59:48.508033 29724 slave.cpp:1709] Launching task '15b24638-8680-445e-9545-8caec46b4cc0' for framework 0fbce162-fb23-40ec-a11f-81576f9191a0-0000 I1116 02:59:48.523377 29724 paths.cpp:530] Trying to chown '/tmp/ContentType_SchedulerTest_KillTask_1_YxFqcj/slaves/0fbce162-fb23-40ec-a11f-81576f9191a0-S0/frameworks/0fbce162-fb23-40ec-a11f-81576f9191a0-0000/executors/default/runs/29c19e2d-05c4-40b1-8c58-880c8477ecf5' to user 'mesos' I1116 02:59:48.531569 29721 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 38.092458ms I1116 02:59:48.531816 29721 replica.cpp:708] Persisted action TRUNCATE at position 4 I1116 02:59:48.532730 29724 slave.cpp:6319] Launching executor 'default' of framework 0fbce162-fb23-40ec-a11f-81576f9191a0-0000 with resources {} in work directory '/tmp/ContentType_SchedulerTest_KillTask_1_YxFqcj/slaves/0fbce162-fb23-40ec-a11f-81576f9191a0-S0/frameworks/0fbce162-fb23-40ec-a11f-81576f9191a0-0000/executors/default/runs/29c19e2d-05c4-40b1-8c58-880c8477ecf5' I1116 02:59:48.533805 29724 slave.cpp:2031] Queued task '15b24638-8680-445e-9545-8caec46b4cc0' for executor 'default' of framework 0fbce162-fb23-40ec-a11f-81576f9191a0-0000 I1116 02:59:48.534473 29724 slave.cpp:868] Successfully attached file '/tmp/ContentType_SchedulerTest_KillTask_1_YxFqcj/slaves/0fbce162-fb23-40ec-a11f-81576f9191a0-S0/frameworks/0fbce162-fb23-40ec-a11f-81576f9191a0-0000/executors/default/runs/29c19e2d-05c4-40b1-8c58-880c8477ecf5' I1116 02:59:48.534780 29724 replica.cpp:691] Replica received learned notice for position 4 from @0.0.0.0:0 I1116 02:59:48.536753 29721 executor.cpp:189] Version: 1.2.0 I1116 02:59:48.539933 29729 executor.cpp:390] Connected with the agent I1116 02:59:48.540729 29729 executor.cpp:291] Sending SUBSCRIBE call to http://172.17.0.2:51027/slave(630)/api/v1/executor I1116 02:59:48.542177 29729 process.cpp:3570] Handling HTTP event for process 'slave(630)' with path: '/slave(630)/api/v1/executor' I1116 02:59:48.543015 29729 http.cpp:277] HTTP POST for /slave(630)/api/v1/executor from 172.17.0.2:43388 I1116 02:59:48.543241 29729 slave.cpp:3086] Received Subscribe request for HTTP executor 'default' of framework 0fbce162-fb23-40ec-a11f-81576f9191a0-0000 I1116 02:59:48.547185 29722 executor.cpp:705] Enqueuing event SUBSCRIBED received from http://172.17.0.2:51027/slave(630)/api/v1/executor *** Aborted at 1479265188 (unix time) try "date -d @1479265188" if you are using GNU date *** PC: @ 0x1b7f352 testing::internal::UntypedFunctionMockerBase::SetOwnerAndName() *** SIGSEGV (@0x109) received by PID 29696 (TID 0x2b7ac1539700) from PID 265; stack trace: *** @ 0x2b7b0cf8b2c7 (unknown) @ 0x2b7b0cf8f5a9 (unknown) @ 0x2b7ab82c5330 (unknown) @ 0x1b7f352 testing::internal::UntypedFunctionMockerBase::SetOwnerAndName() @ 0xd8f9c7 mesos::internal::tests::executor::MockHTTPExecutor<>::subscribed() I1116 02:59:48.579802 29724 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 44.750722ms I1116 02:59:48.580128 29724 leveldb.cpp:399] Deleting ~2 keys from leveldb took 70054ns I1116 02:59:48.580353 29724 replica.cpp:708] Persisted action TRUNCATE at position 4 @ 0xd89443 mesos::internal::tests::executor::MockHTTPExecutor<>::event() @ 0xd8256a mesos::internal::tests::executor::TestMesos<>::events() @ 0xda367d std::_Mem_fn<>::operator()<>() @ 0xd98e45 _ZNSt5_BindIFSt7_Mem_fnIMN5mesos8internal5tests8executor9TestMesosINS1_2v18executor5MesosENS7_5EventEEEFvSt5queueIS9_St5dequeIS9_SaIS9_EEEEEPSA_St12_PlaceholderILi1EEEE6__callIvIRKSF_EILm0ELm1EEEET_OSt5tupleIIDpT0_EESt12_Index_tupleIIXspT1_EEE @ 0xd8ffb7 _ZNSt5_BindIFSt7_Mem_fnIMN5mesos8internal5tests8executor9TestMesosINS1_2v18executor5MesosENS7_5EventEEEFvSt5queueIS9_St5dequeIS9_SaIS9_EEEEEPSA_St12_PlaceholderILi1EEEEclIJRKSF_EvEET0_DpOT_ @ 0xd898de std::_Function_handler<>::_M_invoke() @ 0x2b7ab4f20b37 std::function<>::operator()() @ 0x2b7ab4f191ae process::AsyncExecutorProcess::execute<>() @ 0x2b7ab4f20c58 _ZZN7process8dispatchI7NothingNS_20AsyncExecutorProcessERKSt8functionIFvRKSt5queueIN5mesos2v18executor5EventESt5dequeIS8_SaIS8_EEEEESC_PvSG_SC_SJ_EENS_6FutureIT_EERKNS_3PIDIT0_EEMSO_FSL_T1_T2_T3_ET4_T5_T6_ENKUlPNS_11ProcessBaseEE_clES11_ @ 0x2b7ab4f2caf4 _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchI7NothingNS0_20AsyncExecutorProcessERKSt8functionIFvRKSt5queueIN5mesos2v18executor5EventESt5dequeISC_SaISC_EEEEESG_PvSK_SG_SN_EENS0_6FutureIT_EERKNS0_3PIDIT0_EEMSS_FSP_T1_T2_T3_ET4_T5_T6_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_ @ 0x2b7ab61b6549 std::function<>::operator()() @ 0x2b7ab6198cc9 process::ProcessBase::visit() @ 0x2b7ab61a11a8 process::DispatchEvent::visit() @ 0xa67452 process::ProcessBase::serve() @ 0x2b7ab6194fb6 process::ProcessManager::resume() @ 0x2b7ab6191af5 _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv @ 0x2b7ab61a0950 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE @ 0x2b7ab61a08a7 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv @ 0x2b7ab61a0840 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv @ 0x2b7ab7b46a60 (unknown) @ 0x2b7ab82bd184 start_thread @ 0x2b7ab85cd37d (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-1479261170-31348 Untagged: mesos-1479261170-31348:latest Deleted: sha256:7a5e2fd734c623e334bb7d3f4c100a154ffdb08ebd623d7d4970de9f89b1522b Deleted: sha256:762462a6c38a96cb9f545b3a71e75a7ccf8aa99acbcc3a689eaef9c0b1e678d3 Deleted: sha256:a02c917a7769ebb907393aa4aec262b8035f73b7874c73cf59a9688618882117 Deleted: sha256:82106ed65f2d2ce0573db187deb6046ff5d610c0a61899693168cf779144e087 Deleted: sha256:ed0d05350256a04bb472bf5d13f7016210b5d0bf375de13642e9e19c80026270 Deleted: sha256:f25e5205772c46511dc8fb01d7c5f69d2ffc20272669d4aea49014a2b2a3f3c2 Deleted: sha256:83b08f6721cdc9a8cba2f2e133f13d84af58b194c36783bea26034725865ae19 Deleted: sha256:bc3c1bbe2345e0f29fbc0a2de84aa44378450bf29aecf6cf5f7efe907d5afd7f Deleted: sha256:ce6d22b7a68bb3f83d9dae5a53d7c1ef997debf1890c2c5aa31ba3f38115b450 Deleted: sha256:9d79c757fed0e10dccdaaf7660c0879d525d6ba6033e08a1e1250534fcdf1bd1 Deleted: sha256:f3d55d3848e3b64c544ec760137041cfa07733753530c62cc999e0313b8bd770 Deleted: sha256:c75315f3d1efbc245fe2ebbbbebfebbb73d5285ba0ea95a47d981c5216111880 Deleted: sha256:c2c78ca417da55233273a8c969c693deef3f3378c3be0910c9a164e3cb52c4b2 Deleted: sha256:3cb02dd640b73e5da0fa0e56d510310677a1d61ebaaae8f1212d6bb7e37df7cf Deleted: sha256:526daaa89940740ccf00ba32c4043b4a737f8e388c66d42b9d2aef9f7ce04a6c Deleted: sha256:6674c973c43360d8d40492a1f5d25d622f3092961c8967bb596ac7a9c5fbcdf0 Deleted: sha256:de56dd59855de120f806941be15b24d34aed544864bd3895ffb3d045ae8c167e Deleted: sha256:3391dc15150cc556d32e674f3ce47824bcd75384ae00dd25e3fcce2af5bc4036 Deleted: sha256:3bad869fb0bcc48b9a722dc464f3c4293ec0a20674c590376082d236959352f0 Deleted: sha256:fe8f4cc2be257879f25f57e0951d994361922483ef80f9ba68c17ce9447a3b0b Deleted: sha256:401b00cd4faa3ee70bb8133cb9a27c2b126837107770d098b9007736962a13b9 Deleted: sha256:97fe8cd3369291ff05d3a2a5d0003c7ca8317a34de748cdb1088787eed004c0b Deleted: sha256:09cbf756fe99ae8cadbc1558024fe6debd756af44b30c551a55c661355b00d94 Deleted: sha256:c70dfb9c2dee0612a4b73b60444dfbc45c07a1e0d7d326b6b90f114c60023422 Build step 'Execute shell' marked build as failure
