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/07/20 04:04:46 UTC
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3936
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3936/display/redirect?page=changes>
Changes:
[yujie.jay] Set the `LIBPROCESS_IP` env variable before starting the fetcher.
[yujie.jay] Added MESOS-7796 to 1.1.3 CHANGELOG.
------------------------------------------
[...truncated 2.98 MB...]
I0720 04:04:10.357405 30120 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 916540ns
I0720 04:04:10.357466 30120 replica.cpp:708] Persisted action APPEND at position 1
I0720 04:04:10.359431 30126 registrar.cpp:506] Successfully updated the registry in 7.370752ms
I0720 04:04:10.359709 30126 registrar.cpp:392] Successfully recovered registrar
I0720 04:04:10.359786 30122 log.cpp:596] Attempting to truncate the log to 1
I0720 04:04:10.360013 30119 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0720 04:04:10.360540 30125 master.cpp:1676] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0720 04:04:10.360606 30124 hierarchical.cpp:176] Skipping recovery of hierarchical allocator: nothing to recover
I0720 04:04:10.361469 30121 replica.cpp:537] Replica received write request for position 2 from __req_res__(174)@172.17.0.3:46515
I0720 04:04:10.362437 30121 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 882755ns
I0720 04:04:10.362500 30121 replica.cpp:708] Persisted action TRUNCATE at position 2
I0720 04:04:10.363628 30120 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0720 04:04:10.364671 30120 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 965042ns
I0720 04:04:10.364861 30120 leveldb.cpp:399] Deleting ~1 keys from leveldb took 122848ns
I0720 04:04:10.365030 30120 replica.cpp:708] Persisted action TRUNCATE at position 2
I0720 04:04:10.370028 30100 containerizer.cpp:200] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0720 04:04:10.371078 30100 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges, but is running as user mesos
W0720 04:04:10.371336 30100 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0720 04:04:10.376847 30120 slave.cpp:208] Mesos agent started on 72.17.0.3:46515
I0720 04:04:10.376889 30120 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/HTTPCommandExecutorTest_TerminateWithACK_dmTih5/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_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/HTTPCommandExecutorTest_TerminateWithACK_dmTih5/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="true" --http_credentials="/tmp/HTTPCommandExecutorTest_TerminateWithACK_dmTih5/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.1.3/_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/HTTPCommandExecutorTest_TerminateWithACK_dmTih5" --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/HTTPCommandExecutorTest_TerminateWithACK_Bg5baQ"
I0720 04:04:10.378286 30120 credentials.hpp:86] Loading credential for authentication from '/tmp/HTTPCommandExecutorTest_TerminateWithACK_dmTih5/credential'
I0720 04:04:10.378623 30120 slave.cpp:346] Agent using credential for: test-principal
I0720 04:04:10.378777 30120 credentials.hpp:37] Loading credentials for authentication from '/tmp/HTTPCommandExecutorTest_TerminateWithACK_dmTih5/http_credentials'
I0720 04:04:10.379179 30100 sched.cpp:226] Version: 1.1.3
I0720 04:04:10.379366 30120 http.cpp:887] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0720 04:04:10.379717 30120 http.cpp:887] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0720 04:04:10.380416 30125 sched.cpp:330] New master detected at master@172.17.0.3:46515
I0720 04:04:10.380580 30125 sched.cpp:396] Authenticating with master master@172.17.0.3:46515
I0720 04:04:10.380601 30125 sched.cpp:403] Using default CRAM-MD5 authenticatee
I0720 04:04:10.380957 30122 authenticatee.cpp:121] Creating new client SASL connection
I0720 04:04:10.381397 30125 master.cpp:6680] Authenticating scheduler-4b87e39d-9672-4e37-860c-5a4171929469@172.17.0.3:46515
I0720 04:04:10.381544 30119 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(32)@172.17.0.3:46515
I0720 04:04:10.381949 30121 authenticator.cpp:98] Creating new server SASL connection
I0720 04:04:10.381908 30120 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0720 04:04:10.382288 30120 slave.cpp:541] Agent attributes: [ ]
I0720 04:04:10.382318 30120 slave.cpp:546] Agent hostname: 0567951a2113
I0720 04:04:10.382338 30126 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0720 04:04:10.382400 30126 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0720 04:04:10.382587 30125 authenticator.cpp:204] Received SASL authentication start
I0720 04:04:10.382675 30125 authenticator.cpp:326] Authentication requires more steps
I0720 04:04:10.382798 30119 authenticatee.cpp:259] Received SASL authentication step
I0720 04:04:10.382956 30119 authenticator.cpp:232] Received SASL authentication step
I0720 04:04:10.383019 30119 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0567951a2113' server FQDN: '0567951a2113' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0720 04:04:10.383057 30119 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0720 04:04:10.383121 30119 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0720 04:04:10.383172 30119 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0567951a2113' server FQDN: '0567951a2113' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0720 04:04:10.383208 30119 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0720 04:04:10.383239 30119 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0720 04:04:10.383280 30119 authenticator.cpp:318] Authentication success
I0720 04:04:10.383499 30124 authenticatee.cpp:299] Authentication success
I0720 04:04:10.383540 30121 master.cpp:6710] Successfully authenticated principal 'test-principal' at scheduler-4b87e39d-9672-4e37-860c-5a4171929469@172.17.0.3:46515
I0720 04:04:10.383630 30123 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(32)@172.17.0.3:46515
I0720 04:04:10.383982 30122 sched.cpp:502] Successfully authenticated with master master@172.17.0.3:46515
I0720 04:04:10.384023 30122 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.3:46515
I0720 04:04:10.384174 30122 sched.cpp:853] Will retry registration in 1.501803194secs if necessary
I0720 04:04:10.384421 30123 master.cpp:2596] Received SUBSCRIBE call for framework 'default' at scheduler-4b87e39d-9672-4e37-860c-5a4171929469@172.17.0.3:46515
I0720 04:04:10.384526 30123 master.cpp:2053] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0720 04:04:10.385249 30119 master.cpp:2672] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0720 04:04:10.385382 30123 state.cpp:57] Recovering state from '/tmp/HTTPCommandExecutorTest_TerminateWithACK_Bg5baQ/meta'
I0720 04:04:10.385875 30122 status_update_manager.cpp:203] Recovering status update manager
I0720 04:04:10.386149 30126 sched.cpp:743] Framework registered with bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000
I0720 04:04:10.386231 30126 sched.cpp:757] Scheduler::registered took 31530ns
I0720 04:04:10.386270 30119 containerizer.cpp:555] Recovering containerizer
I0720 04:04:10.386288 30121 hierarchical.cpp:275] Added framework bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000
I0720 04:04:10.386421 30121 hierarchical.cpp:1694] No allocations performed
I0720 04:04:10.386458 30121 hierarchical.cpp:1789] No inverse offers to send out!
I0720 04:04:10.386531 30121 hierarchical.cpp:1286] Performed allocation for 0 agents in 181346ns
I0720 04:04:10.388567 30120 provisioner.cpp:253] Provisioner recovery complete
I0720 04:04:10.389098 30124 slave.cpp:5388] Finished recovery
I0720 04:04:10.389770 30124 slave.cpp:5562] Querying resource estimator for oversubscribable resources
I0720 04:04:10.390343 30119 slave.cpp:915] New master detected at master@172.17.0.3:46515
I0720 04:04:10.390415 30119 slave.cpp:974] Authenticating with master master@172.17.0.3:46515
I0720 04:04:10.390408 30123 status_update_manager.cpp:177] Pausing sending status updates
I0720 04:04:10.390564 30119 slave.cpp:985] Using default CRAM-MD5 authenticatee
I0720 04:04:10.390830 30119 slave.cpp:947] Detecting new master
I0720 04:04:10.390903 30125 authenticatee.cpp:121] Creating new client SASL connection
I0720 04:04:10.391299 30122 master.cpp:6680] Authenticating (1)@172.17.0.3:46515
I0720 04:04:10.391501 30126 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(33)@172.17.0.3:46515
I0720 04:04:10.391978 30121 authenticator.cpp:98] Creating new server SASL connection
I0720 04:04:10.392462 30119 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0720 04:04:10.392627 30119 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0720 04:04:10.392884 30119 authenticator.cpp:204] Received SASL authentication start
I0720 04:04:10.393015 30119 authenticator.cpp:326] Authentication requires more steps
I0720 04:04:10.393287 30119 authenticatee.cpp:259] Received SASL authentication step
I0720 04:04:10.393432 30119 authenticator.cpp:232] Received SASL authentication step
I0720 04:04:10.393481 30119 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0567951a2113' server FQDN: '0567951a2113' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0720 04:04:10.393534 30119 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0720 04:04:10.393600 30119 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0720 04:04:10.393652 30119 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0567951a2113' server FQDN: '0567951a2113' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0720 04:04:10.393749 30119 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0720 04:04:10.393874 30119 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0720 04:04:10.393928 30119 authenticator.cpp:318] Authentication success
I0720 04:04:10.394119 30125 authenticatee.cpp:299] Authentication success
I0720 04:04:10.394193 30121 master.cpp:6710] Successfully authenticated principal 'test-principal' at (1)@172.17.0.3:46515
I0720 04:04:10.394325 30122 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(33)@172.17.0.3:46515
I0720 04:04:10.394603 30124 slave.cpp:1069] Successfully authenticated with master master@172.17.0.3:46515
I0720 04:04:10.394876 30124 slave.cpp:1475] Will retry registration in 16.071498ms if necessary
I0720 04:04:10.395160 30125 master.cpp:5103] Registering agent at (1)@172.17.0.3:46515 (0567951a2113) with id bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-S0
I0720 04:04:10.395853 30119 registrar.cpp:461] Applied 1 operations in 91249ns; attempting to update the registry
I0720 04:04:10.397239 30126 log.cpp:577] Attempting to append 337 bytes to the log
I0720 04:04:10.397574 30120 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 3
I0720 04:04:10.398816 30119 replica.cpp:537] Replica received write request for position 3 from __req_res__(175)@172.17.0.3:46515
I0720 04:04:10.399770 30119 leveldb.cpp:341] Persisting action (356 bytes) to leveldb took 845441ns
I0720 04:04:10.399838 30119 replica.cpp:708] Persisted action APPEND at position 3
I0720 04:04:10.400889 30120 replica.cpp:691] Replica received learned notice for position 3 from @0.0.0.0:0
I0720 04:04:10.401635 30120 leveldb.cpp:341] Persisting action (358 bytes) to leveldb took 673069ns
I0720 04:04:10.401695 30120 replica.cpp:708] Persisted action APPEND at position 3
I0720 04:04:10.404350 30121 registrar.cpp:506] Successfully updated the registry in 8.358144ms
I0720 04:04:10.404664 30119 log.cpp:596] Attempting to truncate the log to 3
I0720 04:04:10.405022 30125 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 4
I0720 04:04:10.405671 30124 slave.cpp:4176] Received ping from slave-observer(8)@172.17.0.3:46515
I0720 04:04:10.405848 30126 master.cpp:5174] Registered agent bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-S0 at (1)@172.17.0.3:46515 (0567951a2113) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0720 04:04:10.406000 30125 replica.cpp:537] Replica received write request for position 4 from __req_res__(176)@172.17.0.3:46515
I0720 04:04:10.406003 30124 slave.cpp:1115] Registered with master master@172.17.0.3:46515; given agent ID bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-S0
I0720 04:04:10.406075 30124 fetcher.cpp:90] Clearing fetcher cache
I0720 04:04:10.406378 30122 status_update_manager.cpp:184] Resuming sending status updates
I0720 04:04:10.406623 30124 slave.cpp:1138] Checkpointing SlaveInfo to '/tmp/HTTPCommandExecutorTest_TerminateWithACK_Bg5baQ/meta/slaves/bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-S0/slave.info'
I0720 04:04:10.406656 30120 hierarchical.cpp:485] Added agent bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-S0 (0567951a2113) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0720 04:04:10.407140 30125 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.055599ms
I0720 04:04:10.407200 30125 replica.cpp:708] Persisted action TRUNCATE at position 4
I0720 04:04:10.408526 30125 replica.cpp:691] Replica received learned notice for position 4 from @0.0.0.0:0
I0720 04:04:10.409631 30120 hierarchical.cpp:1789] No inverse offers to send out!
I0720 04:04:10.409780 30120 hierarchical.cpp:1309] Performed allocation for agent bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-S0 in 2.886253ms
I0720 04:04:10.410683 30126 master.cpp:6509] Sending 1 offers to framework bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000 (default) at scheduler-4b87e39d-9672-4e37-860c-5a4171929469@172.17.0.3:46515
I0720 04:04:10.411835 30120 sched.cpp:917] Scheduler::resourceOffers took 200152ns
I0720 04:04:10.415688 30119 master.cpp:3554] Processing ACCEPT call for offers: [ bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-O0 ] on agent bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-S0 at (1)@172.17.0.3:46515 (0567951a2113) for framework bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000 (default) at scheduler-4b87e39d-9672-4e37-860c-5a4171929469@172.17.0.3:46515
I0720 04:04:10.416074 30119 master.cpp:3159] Authorizing framework principal 'test-principal' to launch task 606bb152-8771-4759-8074-0cfc1e4a6ad1
I0720 04:04:10.420187 30125 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 11.593885ms
I0720 04:04:10.420331 30125 leveldb.cpp:399] Deleting ~2 keys from leveldb took 78567ns
I0720 04:04:10.420357 30125 replica.cpp:708] Persisted action TRUNCATE at position 4
I0720 04:04:10.421072 30124 master.cpp:8262] Adding task 606bb152-8771-4759-8074-0cfc1e4a6ad1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-S0 (0567951a2113)
I0720 04:04:10.421681 30124 master.cpp:4192] Launching task 606bb152-8771-4759-8074-0cfc1e4a6ad1 of framework bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000 (default) at scheduler-4b87e39d-9672-4e37-860c-5a4171929469@172.17.0.3:46515 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-S0 at (1)@172.17.0.3:46515 (0567951a2113)
I0720 04:04:10.423163 30123 slave.cpp:1539] Got assigned task '606bb152-8771-4759-8074-0cfc1e4a6ad1' for framework bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000
I0720 04:04:10.424954 30123 slave.cpp:1701] Launching task '606bb152-8771-4759-8074-0cfc1e4a6ad1' for framework bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000
I0720 04:04:10.426143 30123 paths.cpp:536] Trying to chown '/tmp/HTTPCommandExecutorTest_TerminateWithACK_Bg5baQ/slaves/bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-S0/frameworks/bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000/executors/606bb152-8771-4759-8074-0cfc1e4a6ad1/runs/be2d83fb-ee89-40d4-815e-4a0656158186' to user 'mesos'
I0720 04:04:10.435664 30123 slave.cpp:6286] Launching executor '606bb152-8771-4759-8074-0cfc1e4a6ad1' of framework bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/tmp/HTTPCommandExecutorTest_TerminateWithACK_Bg5baQ/slaves/bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-S0/frameworks/bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000/executors/606bb152-8771-4759-8074-0cfc1e4a6ad1/runs/be2d83fb-ee89-40d4-815e-4a0656158186'
I0720 04:04:10.437300 30124 containerizer.cpp:938] Starting container be2d83fb-ee89-40d4-815e-4a0656158186 for executor '606bb152-8771-4759-8074-0cfc1e4a6ad1' of framework bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000
I0720 04:04:10.437907 30123 slave.cpp:1987] Queued task '606bb152-8771-4759-8074-0cfc1e4a6ad1' for executor '606bb152-8771-4759-8074-0cfc1e4a6ad1' of framework bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000
I0720 04:04:10.438190 30123 slave.cpp:868] Successfully attached file '/tmp/HTTPCommandExecutorTest_TerminateWithACK_Bg5baQ/slaves/bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-S0/frameworks/bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000/executors/606bb152-8771-4759-8074-0cfc1e4a6ad1/runs/be2d83fb-ee89-40d4-815e-4a0656158186'
I0720 04:04:10.448613 30119 containerizer.cpp:1463] Launching 'mesos-containerizer' with flags '--command="{"arguments":["mesos-executor","--launcher_dir=\/mesos\/mesos-1.1.3\/_build\/src"],"shell":false,"value":"\/mesos\/mesos-1.1.3\/_build\/src\/mesos-executor"}" --help="false" --pipe_read="8" --pipe_write="11" --pre_exec_commands="[]" --runtime_directory="/tmp/HTTPCommandExecutorTest_TerminateWithACK_dmTih5/containers/be2d83fb-ee89-40d4-815e-4a0656158186" --unshare_namespace_mnt="false" --user="mesos" --working_directory="/tmp/HTTPCommandExecutorTest_TerminateWithACK_Bg5baQ/slaves/bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-S0/frameworks/bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000/executors/606bb152-8771-4759-8074-0cfc1e4a6ad1/runs/be2d83fb-ee89-40d4-815e-4a0656158186"'
I0720 04:04:10.451189 30119 launcher.cpp:127] Forked child with pid '30141' for container 'be2d83fb-ee89-40d4-815e-4a0656158186'
I0720 04:04:11.331684 30125 hierarchical.cpp:1694] No allocations performed
I0720 04:04:11.331765 30125 hierarchical.cpp:1789] No inverse offers to send out!
I0720 04:04:11.331881 30125 hierarchical.cpp:1286] Performed allocation for 1 agents in 481258ns
I0720 04:04:12.333587 30120 hierarchical.cpp:1694] No allocations performed
I0720 04:04:12.333660 30120 hierarchical.cpp:1789] No inverse offers to send out!
I0720 04:04:12.333742 30120 hierarchical.cpp:1286] Performed allocation for 1 agents in 376535ns
I0720 04:04:13.334905 30120 hierarchical.cpp:1694] No allocations performed
I0720 04:04:13.334982 30120 hierarchical.cpp:1789] No inverse offers to send out!
I0720 04:04:13.335073 30120 hierarchical.cpp:1286] Performed allocation for 1 agents in 367663ns
I0720 04:04:14.336498 30121 hierarchical.cpp:1694] No allocations performed
I0720 04:04:14.336565 30121 hierarchical.cpp:1789] No inverse offers to send out!
I0720 04:04:14.336644 30121 hierarchical.cpp:1286] Performed allocation for 1 agents in 357296ns
I0720 04:04:15.337766 30121 hierarchical.cpp:1694] No allocations performed
I0720 04:04:15.337836 30121 hierarchical.cpp:1789] No inverse offers to send out!
I0720 04:04:15.337923 30121 hierarchical.cpp:1286] Performed allocation for 1 agents in 359522ns
I0720 04:04:16.338866 30123 hierarchical.cpp:1694] No allocations performed
I0720 04:04:16.338937 30123 hierarchical.cpp:1789] No inverse offers to send out!
I0720 04:04:16.339021 30123 hierarchical.cpp:1286] Performed allocation for 1 agents in 373888ns
I0720 04:04:17.339920 30124 hierarchical.cpp:1694] No allocations performed
I0720 04:04:17.340008 30124 hierarchical.cpp:1789] No inverse offers to send out!
I0720 04:04:17.340116 30124 hierarchical.cpp:1286] Performed allocation for 1 agents in 463182ns
I0720 04:04:18.341672 30123 hierarchical.cpp:1694] No allocations performed
I0720 04:04:18.341753 30123 hierarchical.cpp:1789] No inverse offers to send out!
I0720 04:04:18.341859 30123 hierarchical.cpp:1286] Performed allocation for 1 agents in 395482ns
I0720 04:04:19.342561 30123 hierarchical.cpp:1694] No allocations performed
I0720 04:04:19.342629 30123 hierarchical.cpp:1789] No inverse offers to send out!
I0720 04:04:19.342723 30123 hierarchical.cpp:1286] Performed allocation for 1 agents in 354369ns
I0720 04:04:20.344239 30119 hierarchical.cpp:1694] No allocations performed
I0720 04:04:20.344332 30119 hierarchical.cpp:1789] No inverse offers to send out!
I0720 04:04:20.344455 30119 hierarchical.cpp:1286] Performed allocation for 1 agents in 468356ns
I0720 04:04:21.345495 30120 hierarchical.cpp:1694] No allocations performed
I0720 04:04:21.345566 30120 hierarchical.cpp:1789] No inverse offers to send out!
I0720 04:04:21.345654 30120 hierarchical.cpp:1286] Performed allocation for 1 agents in 375001ns
I0720 04:04:22.346701 30126 hierarchical.cpp:1694] No allocations performed
I0720 04:04:22.346768 30126 hierarchical.cpp:1789] No inverse offers to send out!
I0720 04:04:22.346860 30126 hierarchical.cpp:1286] Performed allocation for 1 agents in 394711ns
I0720 04:04:23.348114 30125 hierarchical.cpp:1694] No allocations performed
I0720 04:04:23.348182 30125 hierarchical.cpp:1789] No inverse offers to send out!
I0720 04:04:23.348263 30125 hierarchical.cpp:1286] Performed allocation for 1 agents in 347071ns
I0720 04:04:24.350117 30120 hierarchical.cpp:1694] No allocations performed
I0720 04:04:24.350195 30120 hierarchical.cpp:1789] No inverse offers to send out!
I0720 04:04:24.350292 30120 hierarchical.cpp:1286] Performed allocation for 1 agents in 389949ns
I0720 04:04:25.351487 30121 hierarchical.cpp:1694] No allocations performed
I0720 04:04:25.351567 30121 hierarchical.cpp:1789] No inverse offers to send out!
I0720 04:04:25.351663 30121 hierarchical.cpp:1286] Performed allocation for 1 agents in 387493ns
I0720 04:04:25.406152 30121 slave.cpp:4176] Received ping from slave-observer(8)@172.17.0.3:46515
../../src/tests/command_executor_tests.cpp:280: Failure
Failed to wait 15secs for statusRunning
I0720 04:04:25.416996 30126 master.cpp:1297] Framework bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000 (default) at scheduler-4b87e39d-9672-4e37-860c-5a4171929469@172.17.0.3:46515 disconnected
I0720 04:04:25.417060 30126 master.cpp:2904] Disconnecting framework bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000 (default) at scheduler-4b87e39d-9672-4e37-860c-5a4171929469@172.17.0.3:46515
I0720 04:04:25.417102 30126 master.cpp:2928] Deactivating framework bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000 (default) at scheduler-4b87e39d-9672-4e37-860c-5a4171929469@172.17.0.3:46515
I0720 04:04:25.417207 30126 master.cpp:1310] Giving framework bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000 (default) at scheduler-4b87e39d-9672-4e37-860c-5a4171929469@172.17.0.3:46515 0ns to failover
I0720 04:04:25.417517 30124 hierarchical.cpp:386] Deactivated framework bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000
../../src/tests/command_executor_tests.cpp:269: Failure
Actual function call count doesn't match EXPECT_CALL(sched, statusUpdate(_, _))...
Expected: to be called twice
Actual: never called - unsatisfied and active
../../src/tests/command_executor_tests.cpp:274: Failure
Actual function call count doesn't match EXPECT_CALL(slave, executorTerminated(_, _, _))...
Expected: to be called once
Actual: never called - unsatisfied and active
I0720 04:04:25.427855 30125 master.cpp:6361] Framework failover timeout, removing framework bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000 (default) at scheduler-4b87e39d-9672-4e37-860c-5a4171929469@172.17.0.3:46515
I0720 04:04:25.427932 30125 master.cpp:7095] Removing framework bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000 (default) at scheduler-4b87e39d-9672-4e37-860c-5a4171929469@172.17.0.3:46515
I0720 04:04:25.428321 30125 master.cpp:7640] Updating the state of task 606bb152-8771-4759-8074-0cfc1e4a6ad1 of framework bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0720 04:04:25.429774 30125 master.cpp:7736] Removing task 606bb152-8771-4759-8074-0cfc1e4a6ad1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-0000 on agent bc4ecb94-f120-49fd-b035-f6ddd6ff63f8-S0 at (1)@172.17.0.3:46515 (0567951a2113)
*** Aborted at 1500523465 (unix time) try "date -d @1500523465" if you are using GNU date ***
PC: @ 0x0 (unknown)
*** SIGSEGV (@0x0) received by PID 30100 (TID 0x7f2533118700) from PID 0; stack trace: ***
I0720 04:04:25.434844 30100 master.cpp:1097] Master terminating
@ 0x7f253e0bc370 (unknown)
make[4]: *** [check-local] Segmentation fault (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.1.3/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.1.3/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.1.3/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.1.3/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1500520138-24721
Untagged: mesos-1500520138-24721:latest
Deleted: sha256:3f1d8e87a49c321564de1f72279c9876be24b2b147e9e3d89e51256ce2331e8d
Deleted: sha256:80505e45764b579754de3e5b518af7a3f3e759520f5c7e449258d9c32ca769a3
Deleted: sha256:709654474c6d25b4bde8416f4ad032545ab696d987925b5a9edb9e72f94315bb
Deleted: sha256:8f3e6ec490d504d2adc6a3b519d9cd6d2ba7fe4ef4c3064b3d94840f08dc46c7
Deleted: sha256:82bc309b00612e02d1a57016761eeda883bff3162aadb0b9123d3d5a49c653de
Deleted: sha256:10a65abec4b2ce5e2827822d62d4db1c4af756eb8b66c740f3fc8655ae3ad668
Deleted: sha256:c2feff95b4f2161b8300b4f8e5f47989d0086d6fb3ea749acbfede52b10af1e9
Deleted: sha256:7bb1e111b69a63309616d376114a7f7de0b854683e9cd40f439a06ee928a7e1c
Deleted: sha256:850d84afdf89de8563469d764eaba7db61cd34d6c8784da5c3c8d65a7c31dbd7
Deleted: sha256:2ac51c8b8e93867b87d66ebaf1ea2055b30dce852a2cd8cffcdfe3e6a0fe43b7
Deleted: sha256:cf0f67e4ffa4ae8ceaff9d5c17aaa56f624802828922bc43d18513f4a483fccf
Deleted: sha256:e0cba57916c93c007213091c22b4e5cf30eb9e986d26ae14a6bdc1ab2eb2ccab
Deleted: sha256:006760f85b7b57277f17ccda47607d50304da02815aed5c2216d2fb301d56788
Deleted: sha256:afa9c14023eb45e82435780b259c0389d1fe8a9097f0ef3b4e55105da6bf2aaa
Deleted: sha256:1e27a46bda63167404aae2216b6d013f1f65346be72308d1f187746465d25605
Deleted: sha256:4f54cbb0e31442693f4b1b7b757488c1f75a59b686aa629466b549b747bf74d8
Deleted: sha256:f0bf6e92ee805e46b6557789e9c256b5ee288d326243492c1aff6f07bf3f9301
Deleted: sha256:fc59c961b49d979e2ad2e7b958339a3dc7ea2c5e8588bc02de3816f79d00aae2
Deleted: sha256:6b05a190476d05b87d24e550912c99429a4cf2a849ead849e8f9655b0113b4de
Deleted: sha256:83628592bc26ee10f9e82ae853c6236950b6a7b6d483d14fb4b443245f984df9
Deleted: sha256:2d1f18ba68b5f3f24cd42fcf576869f477f482a2c1d9501f293e198911e1b930
Deleted: sha256:589c560ae90d2b738adf9657d65aada727ca617f75fb03491a13dd6a8fe5ad96
Deleted: sha256:bf9e57502b9f0bcbc145b448da56c3af563e98602599ba1bb57ba62ba69e3ef0
Deleted: sha256:fe3d4495d1c6dc039a109b312458cef3915321979f5e3126579bb3eaf907eea7
Deleted: sha256:4a38b44a3b635f908714829de6915092a8c76f527af0c38b14d42b0527bb689d
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user yujie.jay@gmail.com
Jenkins build is back to normal : Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3937
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3937/display/redirect?page=changes>