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 2016/09/23 02:44:14 UTC
Build failed in Jenkins: Mesos » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6) #2671
See <https://builds.apache.org/job/Mesos/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)/2671/changes>
Changes:
[vinodkone] Terminate when receiving the ACK of terminal status update.
[vinodkone] Added the test `HTTPCommandExecutorTest.TerminateWithACK`.
------------------------------------------
[...truncated 16346 lines...]
I0923 02:43:16.042037 29928 hierarchical.cpp:272] Added framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:16.042207 29928 hierarchical.cpp:1691] No allocations performed
I0923 02:43:16.042227 29935 provisioner.cpp:253] Provisioner recovery complete
I0923 02:43:16.042255 29928 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:16.042322 29928 hierarchical.cpp:1283] Performed allocation for 0 agents in 208888ns
I0923 02:43:16.042646 29930 slave.cpp:5233] Finished recovery
I0923 02:43:16.043126 29930 slave.cpp:5405] Querying resource estimator for oversubscribable resources
I0923 02:43:16.043390 29930 slave.cpp:915] New master detected at master@172.17.0.2:47930
I0923 02:43:16.043406 29929 status_update_manager.cpp:177] Pausing sending status updates
I0923 02:43:16.043431 29930 slave.cpp:974] Authenticating with master master@172.17.0.2:47930
I0923 02:43:16.043498 29930 slave.cpp:985] Using default CRAM-MD5 authenticatee
I0923 02:43:16.043663 29930 slave.cpp:947] Detecting new master
I0923 02:43:16.043711 29923 authenticatee.cpp:121] Creating new client SASL connection
I0923 02:43:16.043967 29926 master.cpp:6583] Authenticating (1)@172.17.0.2:47930
I0923 02:43:16.044075 29930 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(33)@172.17.0.2:47930
I0923 02:43:16.044416 29922 authenticator.cpp:98] Creating new server SASL connection
I0923 02:43:16.044631 29927 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0923 02:43:16.044661 29927 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0923 02:43:16.044860 29932 authenticator.cpp:204] Received SASL authentication start
I0923 02:43:16.044921 29932 authenticator.cpp:326] Authentication requires more steps
I0923 02:43:16.045035 29927 authenticatee.cpp:259] Received SASL authentication step
I0923 02:43:16.045159 29931 authenticator.cpp:232] Received SASL authentication step
I0923 02:43:16.045192 29931 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '3c97c6970bd4' server FQDN: '3c97c6970bd4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0923 02:43:16.045208 29931 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0923 02:43:16.045238 29931 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0923 02:43:16.045260 29931 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '3c97c6970bd4' server FQDN: '3c97c6970bd4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0923 02:43:16.045274 29931 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0923 02:43:16.045286 29931 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0923 02:43:16.045305 29931 authenticator.cpp:318] Authentication success
I0923 02:43:16.045395 29932 authenticatee.cpp:299] Authentication success
I0923 02:43:16.045447 29933 master.cpp:6613] Successfully authenticated principal 'test-principal' at (1)@172.17.0.2:47930
I0923 02:43:16.045616 29924 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(33)@172.17.0.2:47930
I0923 02:43:16.045801 29928 slave.cpp:1069] Successfully authenticated with master master@172.17.0.2:47930
I0923 02:43:16.046003 29928 slave.cpp:1475] Will retry registration in 3.56547ms if necessary
I0923 02:43:16.046241 29923 master.cpp:5040] Registering agent at (1)@172.17.0.2:47930 (3c97c6970bd4) with id e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0
I0923 02:43:16.046787 29927 registrar.cpp:461] Applied 1 operations in 62548ns; attempting to update the registry
I0923 02:43:16.047660 29922 log.cpp:577] Attempting to append 337 bytes to the log
I0923 02:43:16.047811 29932 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 3
I0923 02:43:16.048774 29921 replica.cpp:537] Replica received write request for position 3 from __req_res__(175)@172.17.0.2:47930
I0923 02:43:16.049005 29921 leveldb.cpp:341] Persisting action (356 bytes) to leveldb took 185970ns
I0923 02:43:16.049034 29921 replica.cpp:708] Persisted action APPEND at position 3
I0923 02:43:16.049701 29927 replica.cpp:691] Replica received learned notice for position 3 from @0.0.0.0:0
I0923 02:43:16.050201 29927 leveldb.cpp:341] Persisting action (358 bytes) to leveldb took 458273ns
I0923 02:43:16.050240 29927 replica.cpp:708] Persisted action APPEND at position 3
I0923 02:43:16.050600 29923 slave.cpp:1475] Will retry registration in 16.154092ms if necessary
I0923 02:43:16.050853 29924 master.cpp:5028] Ignoring register agent message from (1)@172.17.0.2:47930 (3c97c6970bd4) as admission is already in progress
I0923 02:43:16.052538 29935 registrar.cpp:506] Successfully updated the registry in 5.690112ms
I0923 02:43:16.052829 29922 log.cpp:596] Attempting to truncate the log to 3
I0923 02:43:16.052976 29930 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 4
I0923 02:43:16.053534 29929 slave.cpp:4089] Received ping from slave-observer(8)@172.17.0.2:47930
I0923 02:43:16.053737 29935 replica.cpp:537] Replica received write request for position 4 from __req_res__(176)@172.17.0.2:47930
I0923 02:43:16.053673 29925 master.cpp:5111] Registered agent e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0 at (1)@172.17.0.2:47930 (3c97c6970bd4) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0923 02:43:16.054078 29929 slave.cpp:1115] Registered with master master@172.17.0.2:47930; given agent ID e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0
I0923 02:43:16.054107 29929 fetcher.cpp:86] Clearing fetcher cache
I0923 02:43:16.054107 29924 hierarchical.cpp:482] Added agent e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0 (3c97c6970bd4) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0923 02:43:16.054278 29936 status_update_manager.cpp:184] Resuming sending status updates
I0923 02:43:16.054528 29929 slave.cpp:1138] Checkpointing SlaveInfo to '/tmp/HTTPCommandExecutorTest_TerminateWithACK_dBJ7er/meta/slaves/e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0/slave.info'
I0923 02:43:16.055235 29935 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.451309ms
I0923 02:43:16.055269 29935 replica.cpp:708] Persisted action TRUNCATE at position 4
I0923 02:43:16.055557 29924 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:16.055745 29924 hierarchical.cpp:1306] Performed allocation for agent e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0 in 1.579948ms
I0923 02:43:16.055964 29922 replica.cpp:691] Replica received learned notice for position 4 from @0.0.0.0:0
I0923 02:43:16.056406 29922 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 403911ns
I0923 02:43:16.056493 29922 leveldb.cpp:399] Deleting ~2 keys from leveldb took 53668ns
I0923 02:43:16.056540 29922 replica.cpp:708] Persisted action TRUNCATE at position 4
I0923 02:43:16.056557 29921 master.cpp:6412] Sending 1 offers to framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 (default) at scheduler-2fdbec71-0c4c-4648-8631-480ef06d00ae@172.17.0.2:47930
I0923 02:43:16.057337 29922 sched.cpp:917] Scheduler::resourceOffers took 134618ns
I0923 02:43:16.060381 29935 master.cpp:3521] Processing ACCEPT call for offers: [ e05f7078-4775-4ae8-9b38-4abacecfcdbe-O0 ] on agent e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0 at (1)@172.17.0.2:47930 (3c97c6970bd4) for framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 (default) at scheduler-2fdbec71-0c4c-4648-8631-480ef06d00ae@172.17.0.2:47930
I0923 02:43:16.060498 29935 master.cpp:3143] Authorizing framework principal 'test-principal' to launch task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4
I0923 02:43:16.064076 29935 master.cpp:8159] Adding task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0 (3c97c6970bd4)
I0923 02:43:16.064434 29935 master.cpp:4121] Launching task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 (default) at scheduler-2fdbec71-0c4c-4648-8631-480ef06d00ae@172.17.0.2:47930 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0 at (1)@172.17.0.2:47930 (3c97c6970bd4)
I0923 02:43:16.065382 29923 slave.cpp:1539] Got assigned task 'd8998cc6-de07-4f15-b2c9-e96adc5ff0a4' for framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:16.066741 29923 slave.cpp:1696] Launching task 'd8998cc6-de07-4f15-b2c9-e96adc5ff0a4' for framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:16.069396 29923 paths.cpp:536] Trying to chown '/tmp/HTTPCommandExecutorTest_TerminateWithACK_dBJ7er/slaves/e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0/frameworks/e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000/executors/d8998cc6-de07-4f15-b2c9-e96adc5ff0a4/runs/5e656207-a68f-4107-b76e-200431c80272' to user 'mesos'
I0923 02:43:16.076390 29923 slave.cpp:6122] Launching executor 'd8998cc6-de07-4f15-b2c9-e96adc5ff0a4' of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/tmp/HTTPCommandExecutorTest_TerminateWithACK_dBJ7er/slaves/e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0/frameworks/e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000/executors/d8998cc6-de07-4f15-b2c9-e96adc5ff0a4/runs/5e656207-a68f-4107-b76e-200431c80272'
I0923 02:43:16.077528 29921 containerizer.cpp:836] Starting container 5e656207-a68f-4107-b76e-200431c80272 for executor 'd8998cc6-de07-4f15-b2c9-e96adc5ff0a4' of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:16.077874 29923 slave.cpp:1982] Queued task 'd8998cc6-de07-4f15-b2c9-e96adc5ff0a4' for executor 'd8998cc6-de07-4f15-b2c9-e96adc5ff0a4' of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:16.078017 29923 slave.cpp:868] Successfully attached file '/tmp/HTTPCommandExecutorTest_TerminateWithACK_dBJ7er/slaves/e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0/frameworks/e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000/executors/d8998cc6-de07-4f15-b2c9-e96adc5ff0a4/runs/5e656207-a68f-4107-b76e-200431c80272'
I0923 02:43:16.084020 29934 containerizer.cpp:1247] Launching 'mesos-containerizer' with flags '--command="{"arguments":["mesos-executor","--launcher_dir=\/mesos\/mesos-1.1.0\/_build\/src"],"shell":false,"value":"\/mesos\/mesos-1.1.0\/_build\/src\/mesos-executor"}" --help="false" --pipe_read="8" --pipe_write="11" --pre_exec_commands="[]" --unshare_namespace_mnt="false" --user="mesos" --working_directory="/tmp/HTTPCommandExecutorTest_TerminateWithACK_dBJ7er/slaves/e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0/frameworks/e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000/executors/d8998cc6-de07-4f15-b2c9-e96adc5ff0a4/runs/5e656207-a68f-4107-b76e-200431c80272"'
I0923 02:43:16.085646 29934 launcher.cpp:153] Forked child with pid '29951' for container '5e656207-a68f-4107-b76e-200431c80272'
I0923 02:43:17.004328 29935 hierarchical.cpp:1691] No allocations performed
I0923 02:43:17.004410 29935 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:17.004509 29935 hierarchical.cpp:1283] Performed allocation for 1 agents in 489425ns
I0923 02:43:18.004923 29922 hierarchical.cpp:1691] No allocations performed
I0923 02:43:18.004971 29922 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:18.005025 29922 hierarchical.cpp:1283] Performed allocation for 1 agents in 219540ns
I0923 02:43:19.005774 29932 hierarchical.cpp:1691] No allocations performed
I0923 02:43:19.005848 29932 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:19.005939 29932 hierarchical.cpp:1283] Performed allocation for 1 agents in 392156ns
I0923 02:43:20.007329 29930 hierarchical.cpp:1691] No allocations performed
I0923 02:43:20.007410 29930 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:20.007504 29930 hierarchical.cpp:1283] Performed allocation for 1 agents in 399801ns
I0923 02:43:21.008908 29934 hierarchical.cpp:1691] No allocations performed
I0923 02:43:21.008983 29934 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:21.009068 29934 hierarchical.cpp:1283] Performed allocation for 1 agents in 382140ns
I0923 02:43:21.904255 29922 process.cpp:3336] Handling HTTP event for process '(1)' with path: '/(1)/api/v1/executor'
I0923 02:43:21.906361 29921 http.cpp:273] HTTP POST for /(1)/api/v1/executor from 172.17.0.2:56295
I0923 02:43:21.906828 29921 slave.cpp:3017] Received Subscribe request for HTTP executor 'd8998cc6-de07-4f15-b2c9-e96adc5ff0a4' of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:21.910060 29921 slave.cpp:2186] Sending queued task 'd8998cc6-de07-4f15-b2c9-e96adc5ff0a4' to executor 'd8998cc6-de07-4f15-b2c9-e96adc5ff0a4' of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 (via HTTP)
I0923 02:43:21.925637 29928 process.cpp:3336] Handling HTTP event for process '(1)' with path: '/(1)/api/v1/executor'
I0923 02:43:21.926372 29932 http.cpp:273] HTTP POST for /(1)/api/v1/executor from 172.17.0.2:56296
I0923 02:43:21.926690 29932 slave.cpp:3606] Handling status update TASK_RUNNING (UUID: 26a348eb-ac0f-486f-81f5-c319218ac7db) for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:21.929265 29922 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 26a348eb-ac0f-486f-81f5-c319218ac7db) for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:21.929342 29922 status_update_manager.cpp:500] Creating StatusUpdate stream for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:21.930002 29922 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 26a348eb-ac0f-486f-81f5-c319218ac7db) for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 to the agent
I0923 02:43:21.930454 29926 slave.cpp:4007] Forwarding the update TASK_RUNNING (UUID: 26a348eb-ac0f-486f-81f5-c319218ac7db) for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 to master@172.17.0.2:47930
I0923 02:43:21.930783 29926 slave.cpp:3901] Status update manager successfully handled status update TASK_RUNNING (UUID: 26a348eb-ac0f-486f-81f5-c319218ac7db) for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:21.931020 29933 master.cpp:5638] Status update TASK_RUNNING (UUID: 26a348eb-ac0f-486f-81f5-c319218ac7db) for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 from agent e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0 at (1)@172.17.0.2:47930 (3c97c6970bd4)
I0923 02:43:21.931116 29933 master.cpp:5700] Forwarding status update TASK_RUNNING (UUID: 26a348eb-ac0f-486f-81f5-c319218ac7db) for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:21.931397 29933 master.cpp:7537] Updating the state of task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0923 02:43:21.931695 29926 sched.cpp:1025] Scheduler::statusUpdate took 166659ns
I0923 02:43:21.932847 29934 master.cpp:4756] Processing ACKNOWLEDGE call 26a348eb-ac0f-486f-81f5-c319218ac7db for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 (default) at scheduler-2fdbec71-0c4c-4648-8631-480ef06d00ae@172.17.0.2:47930 on agent e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0
I0923 02:43:21.933533 29929 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 26a348eb-ac0f-486f-81f5-c319218ac7db) for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:21.933957 29932 slave.cpp:2953] Status update manager successfully handled status update acknowledgement (UUID: 26a348eb-ac0f-486f-81f5-c319218ac7db) for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:22.009801 29934 hierarchical.cpp:1691] No allocations performed
I0923 02:43:22.009870 29934 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:22.009953 29934 hierarchical.cpp:1283] Performed allocation for 1 agents in 317382ns
I0923 02:43:23.011085 29933 hierarchical.cpp:1691] No allocations performed
I0923 02:43:23.011160 29933 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:23.011260 29933 hierarchical.cpp:1283] Performed allocation for 1 agents in 385314ns
I0923 02:43:23.034395 29921 process.cpp:3336] Handling HTTP event for process '(1)' with path: '/(1)/api/v1/executor'
I0923 02:43:23.035356 29923 http.cpp:273] HTTP POST for /(1)/api/v1/executor from 172.17.0.2:56296
I0923 02:43:23.035641 29923 slave.cpp:3606] Handling status update TASK_FINISHED (UUID: cea395ce-3469-4bd9-9774-50bda5e5a6e9) for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:23.038949 29932 status_update_manager.cpp:323] Received status update TASK_FINISHED (UUID: cea395ce-3469-4bd9-9774-50bda5e5a6e9) for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:23.039196 29932 status_update_manager.cpp:377] Forwarding update TASK_FINISHED (UUID: cea395ce-3469-4bd9-9774-50bda5e5a6e9) for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 to the agent
I0923 02:43:23.039518 29923 slave.cpp:4007] Forwarding the update TASK_FINISHED (UUID: cea395ce-3469-4bd9-9774-50bda5e5a6e9) for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 to master@172.17.0.2:47930
I0923 02:43:23.039806 29923 slave.cpp:3901] Status update manager successfully handled status update TASK_FINISHED (UUID: cea395ce-3469-4bd9-9774-50bda5e5a6e9) for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:23.039989 29935 master.cpp:5638] Status update TASK_FINISHED (UUID: cea395ce-3469-4bd9-9774-50bda5e5a6e9) for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 from agent e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0 at (1)@172.17.0.2:47930 (3c97c6970bd4)
I0923 02:43:23.040081 29935 master.cpp:5700] Forwarding status update TASK_FINISHED (UUID: cea395ce-3469-4bd9-9774-50bda5e5a6e9) for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:23.040328 29935 master.cpp:7537] Updating the state of task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 (latest state: TASK_FINISHED, status update state: TASK_FINISHED)
I0923 02:43:23.040575 29925 sched.cpp:1025] Scheduler::statusUpdate took 142330ns
I0923 02:43:23.041259 29935 master.cpp:4756] Processing ACKNOWLEDGE call cea395ce-3469-4bd9-9774-50bda5e5a6e9 for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 (default) at scheduler-2fdbec71-0c4c-4648-8631-480ef06d00ae@172.17.0.2:47930 on agent e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0
I0923 02:43:23.041357 29935 master.cpp:7633] Removing task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 on agent e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0 at (1)@172.17.0.2:47930 (3c97c6970bd4)
I0923 02:43:23.041936 29930 hierarchical.cpp:1015] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0 from framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:23.042037 29926 status_update_manager.cpp:395] Received status update acknowledgement (UUID: cea395ce-3469-4bd9-9774-50bda5e5a6e9) for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:23.042454 29926 status_update_manager.cpp:531] Cleaning up status update stream for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:23.042891 29927 slave.cpp:2953] Status update manager successfully handled status update acknowledgement (UUID: cea395ce-3469-4bd9-9774-50bda5e5a6e9) for task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4 of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:23.042948 29927 slave.cpp:6486] Completing task d8998cc6-de07-4f15-b2c9-e96adc5ff0a4
I0923 02:43:23.093106 29925 containerizer.cpp:1842] Executor for container 5e656207-a68f-4107-b76e-200431c80272 has exited
I0923 02:43:23.093173 29925 containerizer.cpp:1612] Destroying container 5e656207-a68f-4107-b76e-200431c80272
I0923 02:43:23.099184 29936 provisioner.cpp:438] Ignoring destroy request for unknown container 5e656207-a68f-4107-b76e-200431c80272
I0923 02:43:23.099733 29933 slave.cpp:4498] Executor 'd8998cc6-de07-4f15-b2c9-e96adc5ff0a4' of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 exited with status 0
I0923 02:43:23.099833 29933 slave.cpp:4602] Cleaning up executor 'd8998cc6-de07-4f15-b2c9-e96adc5ff0a4' of framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 (via HTTP)
I0923 02:43:23.100574 29933 slave.cpp:4690] Cleaning up framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:23.100728 29923 status_update_manager.cpp:285] Closing status update streams for framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:24.013473 29926 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:24.013602 29926 hierarchical.cpp:1283] Performed allocation for 1 agents in 1.42103ms
I0923 02:43:24.014173 29932 master.cpp:6412] Sending 1 offers to framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 (default) at scheduler-2fdbec71-0c4c-4648-8631-480ef06d00ae@172.17.0.2:47930
I0923 02:43:24.014742 29931 sched.cpp:917] Scheduler::resourceOffers took 19617ns
I0923 02:43:25.014530 29927 hierarchical.cpp:1691] No allocations performed
I0923 02:43:25.014608 29927 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:25.014689 29927 hierarchical.cpp:1283] Performed allocation for 1 agents in 367743ns
I0923 02:43:26.015725 29925 hierarchical.cpp:1691] No allocations performed
I0923 02:43:26.015802 29925 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:26.015889 29925 hierarchical.cpp:1283] Performed allocation for 1 agents in 371195ns
I0923 02:43:27.016644 29928 hierarchical.cpp:1691] No allocations performed
I0923 02:43:27.016721 29928 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:27.016814 29928 hierarchical.cpp:1283] Performed allocation for 1 agents in 391953ns
I0923 02:43:28.017997 29933 hierarchical.cpp:1691] No allocations performed
I0923 02:43:28.018076 29933 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:28.018175 29933 hierarchical.cpp:1283] Performed allocation for 1 agents in 398456ns
I0923 02:43:29.019827 29925 hierarchical.cpp:1691] No allocations performed
I0923 02:43:29.019901 29925 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:29.019978 29925 hierarchical.cpp:1283] Performed allocation for 1 agents in 362977ns
I0923 02:43:30.021181 29921 hierarchical.cpp:1691] No allocations performed
I0923 02:43:30.021265 29921 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:30.021361 29921 hierarchical.cpp:1283] Performed allocation for 1 agents in 409063ns
I0923 02:43:31.022914 29924 hierarchical.cpp:1691] No allocations performed
I0923 02:43:31.023000 29924 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:31.023097 29924 hierarchical.cpp:1283] Performed allocation for 1 agents in 430775ns
I0923 02:43:31.054755 29933 slave.cpp:4089] Received ping from slave-observer(8)@172.17.0.2:47930
I0923 02:43:32.025104 29930 hierarchical.cpp:1691] No allocations performed
I0923 02:43:32.025182 29930 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:32.025264 29930 hierarchical.cpp:1283] Performed allocation for 1 agents in 389671ns
I0923 02:43:33.026278 29931 hierarchical.cpp:1691] No allocations performed
I0923 02:43:33.026361 29931 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:33.026453 29931 hierarchical.cpp:1283] Performed allocation for 1 agents in 442227ns
I0923 02:43:34.027318 29928 hierarchical.cpp:1691] No allocations performed
I0923 02:43:34.027386 29928 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:34.027468 29928 hierarchical.cpp:1283] Performed allocation for 1 agents in 363197ns
I0923 02:43:35.028527 29929 hierarchical.cpp:1691] No allocations performed
I0923 02:43:35.028612 29929 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:35.028712 29929 hierarchical.cpp:1283] Performed allocation for 1 agents in 431106ns
I0923 02:43:36.029585 29927 hierarchical.cpp:1691] No allocations performed
I0923 02:43:36.029659 29927 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:36.029745 29927 hierarchical.cpp:1283] Performed allocation for 1 agents in 373515ns
I0923 02:43:37.030405 29929 hierarchical.cpp:1691] No allocations performed
I0923 02:43:37.030477 29929 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:37.030557 29929 hierarchical.cpp:1283] Performed allocation for 1 agents in 362431ns
I0923 02:43:38.031798 29936 hierarchical.cpp:1691] No allocations performed
I0923 02:43:38.031883 29936 hierarchical.cpp:1786] No inverse offers to send out!
I0923 02:43:38.031982 29936 hierarchical.cpp:1283] Performed allocation for 1 agents in 417291ns
../../src/tests/command_executor_tests.cpp:288: Failure
Failed to wait 15secs for termination
I0923 02:43:38.042558 29922 master.cpp:1297] Framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 (default) at scheduler-2fdbec71-0c4c-4648-8631-480ef06d00ae@172.17.0.2:47930 disconnected
I0923 02:43:38.042604 29922 master.cpp:2888] Disconnecting framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 (default) at scheduler-2fdbec71-0c4c-4648-8631-480ef06d00ae@172.17.0.2:47930
I0923 02:43:38.042649 29922 master.cpp:2912] Deactivating framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 (default) at scheduler-2fdbec71-0c4c-4648-8631-480ef06d00ae@172.17.0.2:47930
I0923 02:43:38.042913 29934 hierarchical.cpp:383] Deactivated framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
W0923 02:43:38.043325 29922 master.hpp:2265] Master attempted to send message to disconnected framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 (default) at scheduler-2fdbec71-0c4c-4648-8631-480ef06d00ae@172.17.0.2:47930
I0923 02:43:38.043445 29922 master.cpp:1310] Giving framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 (default) at scheduler-2fdbec71-0c4c-4648-8631-480ef06d00ae@172.17.0.2:47930 0ns to failover
I0923 02:43:38.043910 29934 hierarchical.cpp:1015] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent e05f7078-4775-4ae8-9b38-4abacecfcdbe-S0 from framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
I0923 02:43:38.045091 29936 master.cpp:6264] Framework failover timeout, removing framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 (default) at scheduler-2fdbec71-0c4c-4648-8631-480ef06d00ae@172.17.0.2:47930
I0923 02:43:38.045131 29936 master.cpp:7000] Removing framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000 (default) at scheduler-2fdbec71-0c4c-4648-8631-480ef06d00ae@172.17.0.2:47930
../../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
*** Aborted at 1474598618 (unix time) try "date -d @1474598618" if you are using GNU date ***
I0923 02:43:38.045728 29923 hierarchical.cpp:334] Removed framework e05f7078-4775-4ae8-9b38-4abacecfcdbe-0000
PC: @ 0x0 (unknown)
*** SIGSEGV (@0x0) received by PID 29902 (TID 0x7f0d9b8f3700) from PID 0; stack trace: ***
@ 0x7f0da72c7100 (unknown)
make[4]: *** [check-local] Segmentation fault
make[4]: Leaving directory `/mesos/mesos-1.1.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.1.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.1.0/_build/src'
make[1]: Leaving directory `/mesos/mesos-1.1.0/_build'
make[1]: *** [check-recursive] Error 1
make: *** [distcheck] Error 1
+ docker rmi mesos-1474597115-26056
Untagged: mesos-1474597115-26056:latest
Deleted: sha256:661dc455fa1c4593ccb4f9725774b4f3d5870007f27e6afcb9090f0c9916ad9c
Deleted: sha256:cab2a11b8796963947d0a5295f3b2973dadeafd8fc8119029558c8c5f2fa88b7
Deleted: sha256:5898d07b99ed9fa854bf9fa4faa40de6a2e0d086d7a82b5957171300f6bf47b9
Deleted: sha256:85732d713607bdf3e73a567bdeb2aa54f52f903ff37dce9910f91b9e3b3819c4
Deleted: sha256:dd920e1fd92629b99020a5e08ade80246878608be12d4751a6da6bdcfdc889c3
Deleted: sha256:6b8172ae4011802779dc37bb52cc4b5ae295be9bd8212491850db72989e285cd
Deleted: sha256:2f8d0cf786683bb96e3c44207b2ba6110df6f80f7f69b608f70eb9a66828a60b
Deleted: sha256:6753e4687fbb336621514c13dd3e142d28a2273e5a01aa21949673c869b038c4
Deleted: sha256:bfd0015573dbefb7c8678a2cc26efbdb68f6f826dcfe457a26afc88321675327
Deleted: sha256:3acb9ee82d931cf558ec90eff82a2cd9efd1b4dc6e8c93bcab026290910267fd
Deleted: sha256:8a30e9238c7c49c124e1d04729e98c450c695668228c2101574aca5b346030eb
Deleted: sha256:261a29ec24aef7c626a54999d693acb78beb05477590e4e0368bab71c0d83dbb
Deleted: sha256:d7ddf75a47545737f02b2a8161a3d51b81cd128a26e62334420295947dcbacfc
Deleted: sha256:b5953aeea970388b175bdd811a395bc4e8fb854dae131b46a809f85517b90291
Deleted: sha256:4085e68d55706a0ba34c344eda2de3701b1ec4fcdd95802991b78e0e25cfcba4
Deleted: sha256:38308ad726daf67eaf76d8b8c9d3d96095a71be1db88be64597cf24effae767b
Deleted: sha256:6431f3cde4aacc50758d9efb9f925d7585c54733793ac3c43bed95dd8be127d3
Deleted: sha256:872fe0c4d4c80bcbec0ba84ddbe5367b135fb71eece2c9f406bf8fa8dd3a5867
Deleted: sha256:3c5b67638db31a490ef176209df0a0492eb3e425a9b46a3dff6577ce6e398a3e
Deleted: sha256:6d66d7eb84630f2a135f52b03589230d263db0cd7996935d4ee01e98a5a84bba
Deleted: sha256:f00bb6424471ccfd053a0db97fdb606f67868f1f1fef89917f40123a4fc462ae
Deleted: sha256:1cd08b974430f18766871620ff620c14b5500ea0760c8c9e440552e2605da02a
Deleted: sha256:1f414e74942f925a830985aeb9ab5c9be2fb33b5a35d8f8cc2c510be9299771b
Deleted: sha256:da0d157b50ac754235d0c0038f7d56c42226a31dc57c7c114ff01e6f90f8be2d
Deleted: sha256:412aae084c42533c530786df220d881c8d82fa662fe29ecfbf5ff0a72562bf60
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6) #2672
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)/2672/changes>